--------------------------------- Captured Out --------------------------------- ---- new test ---- 1788127651.151 sable_ircd uid=1001 1788127651.154 sable_ircd #x1B[2m2026-08-30T22:07:31.153501Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(882023758154436608)), timestamp: 1788127651, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1788127651, name: ServerName("My.Little.Server"), ts: 1788127651, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock() 1788127651.154 sable_ircd #x1B[2m2026-08-30T22:07:31.153608Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127651.154 sable_ircd #x1B[2m2026-08-30T22:07:31.153751Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m#x1B[1mapply_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mevent#x1B[0m#x1B[2m=#x1B[0mEvent { id: EventId(Snowflake(882023758154436608)), timestamp: 1788127651, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1788127651, name: ServerName("My.Little.Server"), ts: 1788127651, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(1) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Server 1788127651.154 sable_ircd #x1B[2m2026-08-30T22:07:31.153829Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023758154436608)), timestamp: 1788127651, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }) 1788127651.154 sable_ircd #x1B[2m2026-08-30T22:07:31.153855Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023758154436608)), timestamp: 1788127651, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] } 1788127651.154 sable_ircd #x1B[2m2026-08-30T22:07:31.153883Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023758154436608)), timestamp: 1788127651, change: EventComplete(EventComplete), users_to_notify: [] }) 1788127651.154 sable_ircd #x1B[2m2026-08-30T22:07:31.153897Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023758154436608)), timestamp: 1788127651, change: EventComplete(EventComplete), users_to_notify: [] } 1788127651.155 sable_ircd #x1B[2m2026-08-30T22:07:31.155254Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from reap_preclients_timer 1788127651.155 sable_ircd #x1B[2m2026-08-30T22:07:31.155293Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(882023758162825217)), timestamp: 1788127651, clock: EventClock(1 => 882023758154436608), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1788127651 }) }; event clock=EventClock(1 => 882023758154436608) my clock=EventClock(1 => 882023758154436608) 1788127651.155 sable_ircd #x1B[2m2026-08-30T22:07:31.155343Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127651.156 sable_ircd #x1B[2m2026-08-30T22:07:31.155511Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023758162825217)), timestamp: 1788127651, change: EventComplete(EventComplete), users_to_notify: [] }) 1788127651.156 sable_ircd #x1B[2m2026-08-30T22:07:31.155534Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023758162825217)), timestamp: 1788127651, change: EventComplete(EventComplete), users_to_notify: [] } 1788127651.247 sable_ircd #x1B[2m2026-08-30T22:07:31.247165Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 1), remote_addr: 127.0.0.1, tls_info: None } 1788127651.247 sable_ircd #x1B[2m2026-08-30T22:07:31.247299Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.247 sable_ircd #x1B[2m2026-08-30T22:07:31.247343Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1788127651.247 sable_ircd #x1B[2m2026-08-30T22:07:31.247438Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 1): :My.Little.Server NOTICE * :*** Looking up your hostname 1788127651.248 sable_ircd #x1B[2m2026-08-30T22:07:31.247569Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.248 sable_ircd #x1B[2m2026-08-30T22:07:31.247617Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"QUIT :chkport" 1788127651.248 sable_ircd #x1B[2m2026-08-30T22:07:31.247709Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 1): ERROR :Client quit 1788127651.248 sable_ircd #x1B[2m2026-08-30T22:07:31.247782Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mCloseConnection(ConnectionId(ListenerId(0), 1)) 1788127651.248 sable_ircd #x1B[2m2026-08-30T22:07:31.247802Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 1) 1788127651.248 sable_ircd #x1B[2m2026-08-30T22:07:31.248188Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m Connection error #x1B[3mconnection#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed 1788127651.248 sable_ircd #x1B[2m2026-08-30T22:07:31.248265Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.248 sable_ircd #x1B[2m2026-08-30T22:07:31.248458Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1788127651.268 1: connects to server. 1788127651.268 1 -> S: CAP LS 302 1788127651.268 sable_ircd #x1B[2m2026-08-30T22:07:31.268250Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 2), remote_addr: 127.0.0.1, tls_info: None } 1788127651.268 sable_ircd #x1B[2m2026-08-30T22:07:31.268337Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.268 sable_ircd #x1B[2m2026-08-30T22:07:31.268368Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1788127651.268 sable_ircd #x1B[2m2026-08-30T22:07:31.268438Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE * :*** Looking up your hostname 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.268569Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.268608Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP LS 302" 1788127651.269 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.268703Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server CAP * LS :message-tags server-time echo-message batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1788127651.269 S -> 1: :My.Little.Server CAP * LS :message-tags server-time echo-message batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1788127651.269 1 -> S: CAP REQ :batch labeled-response 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.269128Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.269161Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m DNS lookup finished for ConnectionId(ListenerId(0), 2): 127.0.0.1/Some(Hostname("localhost")) 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.269192Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE * :*** Found your hostname: localhost 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.269223Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 297, tv_nsec: 390041196 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(), realname: OnceLock(), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.269393Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.269 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1788127651.269 sable_ircd #x1B[2m2026-08-30T22:07:31.269429Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP REQ :batch labeled-response" 1788127651.270 sable_ircd #x1B[2m2026-08-30T22:07:31.269535Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server CAP * ACK :batch labeled-response 1788127651.270 sable_ircd #x1B[2m2026-08-30T22:07:31.269563Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mUpdateConnectionCaps(ConnectionId(ListenerId(0), 2), ClientCapabilitySet(48)) 1788127651.310 S -> 1: :My.Little.Server CAP * ACK :batch labeled-response 1788127651.311 1 -> S: NICK foo 1788127651.311 1 -> S: USER username * * :Realname 1788127651.311 1 -> S: CAP END 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.310831Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.310878Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK foo" 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.310968Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.311005Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER username * * :Realname" 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.311064Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 297, tv_nsec: 390041196 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.311128Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 297, tv_nsec: 390041196 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("foo")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.311180Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from async_handlers 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.311222Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127651.311 sable_ircd #x1B[2m2026-08-30T22:07:31.311249Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP END" 1788127651.632 sable_history #x1B[2m2027-08-30T22:29:02.100719Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014300743718608923)), timestamp: 1819664942, clock: EventClock(1 => 1014295448346693654, 50 => 1014300492060368922, 99 => 1014295451547348996), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664942 }) }; event clock=EventClock(1 => 1014295448346693654, 50 => 1014300492060368922, 99 => 1014295451547348996) my clock=EventClock(1 => 1014295448346693654, 50 => 1014300492060368922, 99 => 1014295451547348996) 1788127651.632 sable_history #x1B[2m2027-08-30T22:29:02.102164Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127651.649 sable_history #x1B[2m2027-08-30T22:17:50.148261Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014297925347647503)), timestamp: 1819664270, clock: EventClock(1 => 1014295224354082831, 50 => 1014297673752322062, 99 => 1014295214413983748), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664270 }) }; event clock=EventClock(1 => 1014295224354082831, 50 => 1014297673752322062, 99 => 1014295214413983748) my clock=EventClock(1 => 1014295224354082831, 50 => 1014297673752322062, 99 => 1014295214413983748) 1788127651.649 sable_history #x1B[2m2027-08-30T22:17:50.149496Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.065881Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::complete_progress #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 297, tv_nsec: 390041196 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("foo")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mflag#x1B[0m#x1B[2m=#x1B[0mCapNegotiation #x1B[3mresult#x1B[0m#x1B[2m=#x1B[0mtrue 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.065964Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mRegisterClient(ConnectionId(ListenerId(0), 2)) 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.066113Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(882023761983836162)), timestamp: 1788127652, clock: EventClock(1 => 882023758162825217), target: User(UserId(Snowflake(882023761983836160))), details: NewUser(NewUser { nickname: Nickname("foo"), username: Username("username"), visible_hostname: Hostname("localhost"), realname: Realname("Realname"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(882023761983836161)), NewUserConnection { user: UserId(Snowflake(882023761983836160)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1788127652 })) }) }; event clock=EventClock(1 => 882023758162825217) my clock=EventClock(1 => 882023758162825217) 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.066172Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.066353Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023761983836162)), timestamp: 1788127652, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(882023761983836160)), 1) }), users_to_notify: [] }) 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.066394Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023761983836162)), timestamp: 1788127652, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(882023761983836160)), 1) }), users_to_notify: [] } 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.066424Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023761983836162)), timestamp: 1788127652, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(882023761983836160)), 2), connection: UserConnectionId(Snowflake(882023761983836161)) }), users_to_notify: [] }) 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.066437Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023761983836162)), timestamp: 1788127652, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(882023761983836160)), 2), connection: UserConnectionId(Snowflake(882023761983836161)) }), users_to_notify: [] } 1788127652.066 sable_ircd #x1B[2m2026-08-30T22:07:32.066470Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066493Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066505Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 003 foo :This server was created 2026-08-30 22:07:32.066500318 UTC 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066525Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066556Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 005 foo EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066570Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 422 foo :MOTD File is missing 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066580Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :foo!username@localhost MODE foo + 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066591Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066615Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023761983836162)), timestamp: 1788127652, change: EventComplete(EventComplete), users_to_notify: [] }) 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.066636Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023761983836162)), timestamp: 1788127652, change: EventComplete(EventComplete), users_to_notify: [] } 1788127652.067 S -> 1: :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo 1788127652.067 1 -> S: PING foo 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.067055Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127652.067 sable_ircd #x1B[2m2026-08-30T22:07:32.067085Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING foo" 1788127652.167 1 -> S: PING synchronize298.288614014 1788127652.167 S -> 1: :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1788127652.167 S -> 1: :My.Little.Server 003 foo :This server was created 2026-08-30 22:07:32.066500318 UTC 1788127652.167 S -> 1: :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1788127652.167 S -> 1: :My.Little.Server 005 foo EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1788127652.167 S -> 1: :My.Little.Server 422 foo :MOTD File is missing 1788127652.167 S -> 1: :foo!username@localhost MODE foo + 1788127652.167 S -> 1: :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1788127652.167 sable_ircd #x1B[2m2026-08-30T22:07:32.167355Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127652.167 sable_ircd #x1B[2m2026-08-30T22:07:32.167413Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize298.288614014" 1788127652.509 sable_history #x1B[2m2027-08-30T22:31:51.009577Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014301452174303262)), timestamp: 1819665111, clock: EventClock(1 => 1014295401760559126, 50 => 1014301200516063261, 99 => 1014295404927660036), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819665111 }) }; event clock=EventClock(1 => 1014295401760559126, 50 => 1014301200516063261, 99 => 1014295404927660036) my clock=EventClock(1 => 1014295401760559126, 50 => 1014301200516063261, 99 => 1014295404927660036) 1788127652.509 sable_history #x1B[2m2027-08-30T22:31:51.011005Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127652.899 sable_history #x1B[2m2027-08-30T22:21:35.383154Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014298870051708947)), timestamp: 1819664495, clock: EventClock(1 => 1014295842380582945, 50 => 1014298618393468946, 99 => 1014295842997547013), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664495 }) }; event clock=EventClock(1 => 1014295842380582945, 50 => 1014298618393468946, 99 => 1014295842997547013) my clock=EventClock(1 => 1014295842380582945, 50 => 1014298618393468946, 99 => 1014295842997547013) 1788127652.899 sable_history #x1B[2m2027-08-30T22:21:35.384708Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127653.126 sable_history #x1B[2m2027-08-30T22:34:40.339672Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014302162395799585)), timestamp: 1819665280, clock: EventClock(1 => 1014295353727389718, 50 => 1014301910741753888, 99 => 1014295356898684932), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819665280 }) }; event clock=EventClock(1 => 1014295353727389718, 50 => 1014301910741753888, 99 => 1014295356898684932) my clock=EventClock(1 => 1014295353727389718, 50 => 1014301910741753888, 99 => 1014295356898684932) 1788127653.126 sable_history #x1B[2m2027-08-30T22:34:40.341193Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127653.137 sable_history #x1B[2m2027-08-30T22:18:47.716401Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014298166805340175)), timestamp: 1819664327, clock: EventClock(1 => 1014295135892017153, 50 => 1014297915147100174), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664327 }) }; event clock=EventClock(1 => 1014295135892017153, 50 => 1014297915147100174) my clock=EventClock(1 => 1014295135892017153, 50 => 1014297915147100174) 1788127653.137 sable_history #x1B[2m2027-08-30T22:18:47.717602Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127653.168 1: waiting… 1788127653.172 sable_ircd #x1B[2m2026-08-30T22:07:33.171530Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server PONG My.Little.Server :foo 1788127653.172 S -> 1: :My.Little.Server PONG My.Little.Server :foo 1788127653.637 sable_history #x1B[2m2027-08-30T22:37:46.047717Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014302941315801124)), timestamp: 1819665466, clock: EventClock(1 => 1014295552021499922, 50 => 1014302689657561123, 99 => 1014295555868078086), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819665466 }) }; event clock=EventClock(1 => 1014295552021499922, 50 => 1014302689657561123, 99 => 1014295555868078086) my clock=EventClock(1 => 1014295552021499922, 50 => 1014302689657561123, 99 => 1014295555868078086) 1788127653.637 sable_history #x1B[2m2027-08-30T22:37:46.049169Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127653.756 sable_history #x1B[2m2027-08-30T22:18:48.192219Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014298168801828879)), timestamp: 1819664328, clock: EventClock(1 => 1014295138538622977, 50 => 1014297917147783182), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664328 }) }; event clock=EventClock(1 => 1014295138538622977, 50 => 1014297917147783182) my clock=EventClock(1 => 1014295138538622977, 50 => 1014297917147783182) 1788127653.756 sable_history #x1B[2m2027-08-30T22:18:48.193908Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127653.964 sable_history #x1B[2m2027-08-30T22:24:24.671236Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014299580097044502)), timestamp: 1819664664, clock: EventClock(1 => 1014295541992919062, 50 => 1014299328438804501, 99 => 1014295545160019972), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664664 }) }; event clock=EventClock(1 => 1014295541992919062, 50 => 1014299328438804501, 99 => 1014295545160019972) my clock=EventClock(1 => 1014295541992919062, 50 => 1014299328438804501, 99 => 1014295545160019972) 1788127653.964 sable_history #x1B[2m2027-08-30T22:24:24.672634Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127654.173 1: waiting… 1788127654.178 sable_ircd #x1B[2m2026-08-30T22:07:34.177812Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server PONG My.Little.Server :synchronize298.288614014 1788127654.178 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize298.288614014 1788127654.313 sable_history #x1B[2m2027-08-30T22:18:48.942839Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014298171951751183)), timestamp: 1819664328, clock: EventClock(1 => 1014295140782575617, 50 => 1014297920289316878), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664328 }) }; event clock=EventClock(1 => 1014295140782575617, 50 => 1014297920289316878) my clock=EventClock(1 => 1014295140782575617, 50 => 1014297920289316878) 1788127654.313 sable_history #x1B[2m2027-08-30T22:18:48.944473Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127654.700 sable_history #x1B[2m2027-08-30T22:27:14.293829Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014300291547471897)), timestamp: 1819664834, clock: EventClock(1 => 1014295494442094614, 50 => 1014300039889231896, 99 => 1014295497634361348), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664834 }) }; event clock=EventClock(1 => 1014295494442094614, 50 => 1014300039889231896, 99 => 1014295497634361348) my clock=EventClock(1 => 1014295494442094614, 50 => 1014300039889231896, 99 => 1014295497634361348) 1788127654.700 sable_history #x1B[2m2027-08-30T22:27:14.295348Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127654.746 sable_history #x1B[2m2027-08-30T22:18:49.167772Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014298172895469583)), timestamp: 1819664329, clock: EventClock(1 => 1014295142766481409, 50 => 1014297921241423886), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664329 }) }; event clock=EventClock(1 => 1014295142766481409, 50 => 1014297921241423886) my clock=EventClock(1 => 1014295142766481409, 50 => 1014297921241423886) 1788127654.746 sable_history #x1B[2m2027-08-30T22:18:49.169148Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127654.879 1 -> S: @label=label1 JOIN #valid,inv@lid 1788127654.879 sable_ircd #x1B[2m2026-08-30T22:07:34.879365Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127654.879 sable_ircd #x1B[2m2026-08-30T22:07:34.879426Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"@label=label1 JOIN #valid,inv@lid" 1788127654.979 1 -> S: PING synchronize301.10075083 1788127654.980 sable_ircd #x1B[2m2026-08-30T22:07:34.979510Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1788127654.980 sable_ircd #x1B[2m2026-08-30T22:07:34.979554Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize301.10075083" 1788127655.128 sable_history #x1B[2m2027-08-30T22:18:49.548074Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014298174489305103)), timestamp: 1819664329, clock: EventClock(1 => 1014295144268042241, 50 => 1014297922831065102), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664329 }) }; event clock=EventClock(1 => 1014295144268042241, 50 => 1014297922831065102) my clock=EventClock(1 => 1014295144268042241, 50 => 1014297922831065102) 1788127655.128 sable_history #x1B[2m2027-08-30T22:18:49.549704Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.231465Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(882023775258808323)), timestamp: 1788127655, clock: EventClock(1 => 882023761983836162), target: Channel(ChannelId(Snowflake(882023775258808322))), details: NewChannel(NewChannel { name: ChannelName("#valid"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 882023761983836162) my clock=EventClock(1 => 882023761983836162) 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.231613Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.231684Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(882023775258808324)), timestamp: 1788127655, clock: EventClock(1 => 882023775258808323), target: Membership(MembershipId(UserId(Snowflake(882023761983836160)), ChannelId(Snowflake(882023775258808322)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(882023775258808322)), user: UserId(Snowflake(882023761983836160)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 882023775258808323) my clock=EventClock(1 => 882023775258808323) 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.231789Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.231790Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from async_handlers 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.231964Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023775258808323)), timestamp: 1788127655, change: EventComplete(EventComplete), users_to_notify: [] }) 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.231990Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023775258808323)), timestamp: 1788127655, change: EventComplete(EventComplete), users_to_notify: [] } 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232016Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023775258808324)), timestamp: 1788127655, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(882023761983836160)), ChannelId(Snowflake(882023775258808322))), user: HistoricUserId(UserId(Snowflake(882023761983836160)), 2) }), users_to_notify: [UserId(Snowflake(882023761983836160))] }) 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232030Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023775258808324)), timestamp: 1788127655, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(882023761983836160)), ChannelId(Snowflake(882023775258808322))), user: HistoricUserId(UserId(Snowflake(882023761983836160)), 2) }), users_to_notify: [UserId(Snowflake(882023761983836160))] } 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232076Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): @label=label1 BATCH +46d3106c5b489a1347053bda6a188b29 labeled-response 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232096Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): @batch=46d3106c5b489a1347053bda6a188b29 :My.Little.Server 479 foo inv@lid :Illegal channel name 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232106Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): @batch=46d3106c5b489a1347053bda6a188b29 :foo!username@localhost JOIN #valid 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232122Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): @batch=46d3106c5b489a1347053bda6a188b29 :foo!username@localhost MODE #valid +o foo 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232150Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): @batch=46d3106c5b489a1347053bda6a188b29 :My.Little.Server 353 foo = #valid :@foo 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232162Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): @batch=46d3106c5b489a1347053bda6a188b29 :My.Little.Server 366 foo #valid :End of names list 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232184Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(882023775258808324)), timestamp: 1788127655, change: EventComplete(EventComplete), users_to_notify: [] }) 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232195Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(882023775258808324)), timestamp: 1788127655, change: EventComplete(EventComplete), users_to_notify: [] } 1788127655.232 sable_ircd #x1B[2m2026-08-30T22:07:35.232206Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): BATCH -46d3106c5b489a1347053bda6a188b29 1788127655.233 S -> 1: @label=label1 BATCH +46d3106c5b489a1347053bda6a188b29 labeled-response 1788127655.233 S -> 1: @batch=46d3106c5b489a1347053bda6a188b29 :My.Little.Server 479 foo inv@lid :Illegal channel name 1788127655.233 S -> 1: @batch=46d3106c5b489a1347053bda6a188b29 :foo!username@localhost JOIN #valid 1788127655.233 S -> 1: @batch=46d3106c5b489a1347053bda6a188b29 :foo!username@localhost MODE #valid +o foo 1788127655.233 S -> 1: @batch=46d3106c5b489a1347053bda6a188b29 :My.Little.Server 353 foo = #valid :@foo 1788127655.233 S -> 1: @batch=46d3106c5b489a1347053bda6a188b29 :My.Little.Server 366 foo #valid :End of names list 1788127655.233 S -> 1: BATCH -46d3106c5b489a1347053bda6a188b29 1788127655.632 sable_history #x1B[2m2027-08-30T22:30:02.100795Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014300995376848924)), timestamp: 1819665002, clock: EventClock(1 => 1014295448346693654, 50 => 1014300743718608923, 99 => 1014295451547348996), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819665002 }) }; event clock=EventClock(1 => 1014295448346693654, 50 => 1014300743718608923, 99 => 1014295451547348996) my clock=EventClock(1 => 1014295448346693654, 50 => 1014300743718608923, 99 => 1014295451547348996) 1788127655.632 sable_history #x1B[2m2027-08-30T22:30:02.102208Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127655.649 sable_history #x1B[2m2027-08-30T22:18:50.148128Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(1014298177005887504)), timestamp: 1819664330, clock: EventClock(1 => 1014295224354082831, 50 => 1014297925347647503, 99 => 1014295214413983748), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819664330 }) }; event clock=EventClock(1 => 1014295224354082831, 50 => 1014297925347647503, 99 => 1014295214413983748) my clock=EventClock(1 => 1014295224354082831, 50 => 1014297925347647503, 99 => 1014295214413983748) 1788127655.649 sable_history #x1B[2m2027-08-30T22:18:50.149200Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1788127656.234 1: waiting… 1788127656.238 sable_ircd #x1B[2m2026-08-30T22:07:36.237798Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server PONG My.Little.Server :synchronize301.10075083 1788127656.238 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize301.10075083 1788127656.243 1: disconnects from server.