--------------------------------- Captured Out ---------------------------------
---- new test ----
1788127851.468 sable_ircd     uid=1001
1788127851.471 sable_ircd     #x1B[2m2026-08-30T22:10:51.471178Z#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(882024598349025280)), timestamp: 1788127851, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1788127851, name: ServerName("My.Little.Server"), ts: 1788127851, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1788127851.471 sable_ircd     #x1B[2m2026-08-30T22:10:51.471268Z#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
1788127851.471 sable_ircd     #x1B[2m2026-08-30T22:10:51.471438Z#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(882024598349025280)), timestamp: 1788127851, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1788127851, name: ServerName("My.Little.Server"), ts: 1788127851, 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
1788127851.472 sable_ircd     #x1B[2m2026-08-30T22:10:51.471526Z#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(882024598349025280)), timestamp: 1788127851, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1788127851.472 sable_ircd     #x1B[2m2026-08-30T22:10:51.471558Z#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(882024598349025280)), timestamp: 1788127851, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1788127851.472 sable_ircd     #x1B[2m2026-08-30T22:10:51.471598Z#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(882024598349025280)), timestamp: 1788127851, change: EventComplete(EventComplete), users_to_notify: [] })
1788127851.472 sable_ircd     #x1B[2m2026-08-30T22:10:51.471618Z#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(882024598349025280)), timestamp: 1788127851, change: EventComplete(EventComplete), users_to_notify: [] }
1788127851.472 sable_ircd     #x1B[2m2026-08-30T22:10:51.472241Z#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
1788127851.472 sable_ircd     #x1B[2m2026-08-30T22:10:51.472286Z#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(882024598353219585)), timestamp: 1788127851, clock: EventClock(1 => 882024598349025280), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1788127851 }) }; event clock=EventClock(1 => 882024598349025280) my clock=EventClock(1 => 882024598349025280)
1788127851.472 sable_ircd     #x1B[2m2026-08-30T22:10:51.472337Z#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
1788127851.473 sable_ircd     #x1B[2m2026-08-30T22:10:51.472526Z#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(882024598353219585)), timestamp: 1788127851, change: EventComplete(EventComplete), users_to_notify: [] })
1788127851.473 sable_ircd     #x1B[2m2026-08-30T22:10:51.472549Z#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(882024598353219585)), timestamp: 1788127851, change: EventComplete(EventComplete), users_to_notify: [] }
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.564723Z#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 }
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.564885Z#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
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.564945Z#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
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.565009Z#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
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.565144Z#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
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.565189Z#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"
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.565283Z#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
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.565354Z#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))
1788127851.565 sable_ircd     #x1B[2m2026-08-30T22:10:51.565371Z#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)
1788127851.566 sable_ircd     #x1B[2m2026-08-30T22:10:51.565692Z#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
1788127851.566 sable_ircd     #x1B[2m2026-08-30T22:10:51.565752Z#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
1788127851.566 sable_ircd     #x1B[2m2026-08-30T22:10:51.565895Z#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
1788127851.586 1: connects to server.
1788127851.586 1 -> S: CAP LS 302
1788127851.586 sable_ircd     #x1B[2m2026-08-30T22:10:51.585805Z#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 }
1788127851.586 sable_ircd     #x1B[2m2026-08-30T22:10:51.585902Z#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
1788127851.586 sable_ircd     #x1B[2m2026-08-30T22:10:51.585931Z#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
1788127851.586 sable_ircd     #x1B[2m2026-08-30T22:10:51.585969Z#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
1788127851.586 sable_ircd     #x1B[2m2026-08-30T22:10:51.586059Z#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
1788127851.586 sable_ircd     #x1B[2m2026-08-30T22:10:51.586096Z#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"
1788127851.586 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1788127851.586 sable_ircd     #x1B[2m2026-08-30T22:10:51.586177Z#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
1788127851.586 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
1788127851.586 1 -> S: CAP REQ :batch labeled-response
1788127851.587 sable_ircd     #x1B[2m2026-08-30T22:10:51.586603Z#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
1788127851.587 sable_ircd     #x1B[2m2026-08-30T22:10:51.586632Z#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"))
1788127851.587 sable_ircd     #x1B[2m2026-08-30T22:10:51.586652Z#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
1788127851.587 sable_ircd     #x1B[2m2026-08-30T22:10:51.586681Z#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: 497, tv_nsec: 707576002 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(<uninit>), realname: OnceLock(<uninit>), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1788127851.587 sable_ircd     #x1B[2m2026-08-30T22:10:51.586817Z#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
1788127851.587 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1788127851.587 sable_ircd     #x1B[2m2026-08-30T22:10:51.586852Z#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"
1788127851.587 sable_ircd     #x1B[2m2026-08-30T22:10:51.586945Z#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
1788127851.587 sable_ircd     #x1B[2m2026-08-30T22:10:51.586968Z#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))
1788127851.627 S -> 1: :My.Little.Server CAP * ACK :batch labeled-response
1788127851.628 1 -> S: NICK alice
1788127851.628 1 -> S: USER username * * :Realname
1788127851.628 1 -> S: CAP END
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.627901Z#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
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.627941Z#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 alice"
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.628045Z#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: 497, tv_nsec: 707576002 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("alice")), realname: OnceLock(<uninit>), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.628097Z#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
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.628118Z#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
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.628135Z#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"
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.628180Z#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: 497, tv_nsec: 707576002 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("alice")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.628205Z#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
1788127851.628 sable_ircd     #x1B[2m2026-08-30T22:10:51.628219Z#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"
1788127851.632 sable_history  #x1B[2m2027-08-30T23:19:02.100674Z#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(1014313326630608973)), timestamp: 1819667942, clock: EventClock(1 => 1014295448346693654, 50 => 1014313074972368972, 99 => 1014295451547348996), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667942 }) }; event clock=EventClock(1 => 1014295448346693654, 50 => 1014313074972368972, 99 => 1014295451547348996) my clock=EventClock(1 => 1014295448346693654, 50 => 1014313074972368972, 99 => 1014295451547348996)
1788127851.632 sable_history  #x1B[2m2027-08-30T23:19:02.102102Z#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
1788127851.649 sable_history  #x1B[2m2027-08-30T23:07: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(1014310508259647553)), timestamp: 1819667270, clock: EventClock(1 => 1014295224354082831, 50 => 1014310256601407552, 99 => 1014295214413983748), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667270 }) }; event clock=EventClock(1 => 1014295224354082831, 50 => 1014310256601407552, 99 => 1014295214413983748) my clock=EventClock(1 => 1014295224354082831, 50 => 1014310256601407552, 99 => 1014295214413983748)
1788127851.649 sable_history  #x1B[2m2027-08-30T23:07:50.149235Z#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
1788127852.131 sable_ircd     #x1B[2m2026-08-30T22:10:52.131297Z#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: 497, tv_nsec: 707576002 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("alice")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mflag#x1B[0m#x1B[2m=#x1B[0mCapNegotiation #x1B[3mresult#x1B[0m#x1B[2m=#x1B[0mtrue
1788127852.131 sable_ircd     #x1B[2m2026-08-30T22:10:52.131408Z#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))
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.131617Z#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(882024601117265922)), timestamp: 1788127852, clock: EventClock(1 => 882024598353219585), target: User(UserId(Snowflake(882024601117265920))), details: NewUser(NewUser { nickname: Nickname("alice"), 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(882024601117265921)), NewUserConnection { user: UserId(Snowflake(882024601117265920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1788127852 })) }) }; event clock=EventClock(1 => 882024598353219585) my clock=EventClock(1 => 882024598353219585)
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.131688Z#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
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.131896Z#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(882024601117265922)), timestamp: 1788127852, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(882024601117265920)), 1) }), users_to_notify: [] })
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.131922Z#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(882024601117265922)), timestamp: 1788127852, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(882024601117265920)), 1) }), users_to_notify: [] }
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.131947Z#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(882024601117265922)), timestamp: 1788127852, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(882024601117265920)), 2), connection: UserConnectionId(Snowflake(882024601117265921)) }), users_to_notify: [] })
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.131960Z#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(882024601117265922)), timestamp: 1788127852, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(882024601117265920)), 2), connection: UserConnectionId(Snowflake(882024601117265921)) }), users_to_notify: [] }
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.131996Z#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 alice :Welcome to the test Internet Relay Chat network, alice
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132027Z#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 alice :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132051Z#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 alice :This server was created 2026-08-30 22:10:52.132042083 UTC
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132068Z#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 alice My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132096Z#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 alice 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
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132116Z#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 alice :MOTD File is missing
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132147Z#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): :alice!username@localhost MODE alice +
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132163Z#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 alice :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132188Z#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(882024601117265922)), timestamp: 1788127852, change: EventComplete(EventComplete), users_to_notify: [] })
1788127852.132 sable_ircd     #x1B[2m2026-08-30T22:10:52.132205Z#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(882024601117265922)), timestamp: 1788127852, change: EventComplete(EventComplete), users_to_notify: [] }
1788127852.132 S -> 1: :My.Little.Server 001 alice :Welcome to the test Internet Relay Chat network, alice
1788127852.132 1 -> S: PING foo
1788127852.133 sable_ircd     #x1B[2m2026-08-30T22:10:52.132563Z#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
1788127852.133 sable_ircd     #x1B[2m2026-08-30T22:10:52.132594Z#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"
1788127852.233 1 -> S: PING synchronize498.354121889
1788127852.233 S -> 1: :My.Little.Server 002 alice :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1788127852.233 S -> 1: :My.Little.Server 003 alice :This server was created 2026-08-30 22:10:52.132042083 UTC
1788127852.233 S -> 1: :My.Little.Server 004 alice My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1788127852.233 S -> 1: :My.Little.Server 005 alice 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
1788127852.233 S -> 1: :My.Little.Server 422 alice :MOTD File is missing
1788127852.233 S -> 1: :alice!username@localhost MODE alice +
1788127852.233 S -> 1: :My.Little.Server NOTICE alice :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1788127852.233 sable_ircd     #x1B[2m2026-08-30T22:10:52.233083Z#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
1788127852.233 sable_ircd     #x1B[2m2026-08-30T22:10:52.233142Z#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 synchronize498.354121889"
1788127852.509 sable_history  #x1B[2m2027-08-30T23:21:51.009532Z#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(1014314035086303312)), timestamp: 1819668111, clock: EventClock(1 => 1014295401760559126, 50 => 1014313783432257615, 99 => 1014295404927660036), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819668111 }) }; event clock=EventClock(1 => 1014295401760559126, 50 => 1014313783432257615, 99 => 1014295404927660036) my clock=EventClock(1 => 1014295401760559126, 50 => 1014313783432257615, 99 => 1014295404927660036)
1788127852.509 sable_history  #x1B[2m2027-08-30T23:21:51.010973Z#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
1788127852.899 sable_history  #x1B[2m2027-08-30T23:11:35.382891Z#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(1014311452963708997)), timestamp: 1819667495, clock: EventClock(1 => 1014295842380582945, 50 => 1014311201305468996, 99 => 1014295842997547013), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667495 }) }; event clock=EventClock(1 => 1014295842380582945, 50 => 1014311201305468996, 99 => 1014295842997547013) my clock=EventClock(1 => 1014295842380582945, 50 => 1014311201305468996, 99 => 1014295842997547013)
1788127852.899 sable_history  #x1B[2m2027-08-30T23:11:35.384302Z#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
1788127853.126 sable_history  #x1B[2m2027-08-30T23:24:40.339939Z#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(1014314745311993939)), timestamp: 1819668280, clock: EventClock(1 => 1014295353727389718, 50 => 1014314493649559634, 99 => 1014295356898684932), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819668280 }) }; event clock=EventClock(1 => 1014295353727389718, 50 => 1014314493649559634, 99 => 1014295356898684932) my clock=EventClock(1 => 1014295353727389718, 50 => 1014314493649559634, 99 => 1014295356898684932)
1788127853.126 sable_history  #x1B[2m2027-08-30T23:24:40.341446Z#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
1788127853.137 sable_history  #x1B[2m2027-08-30T23:08:47.716175Z#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(1014310749717340225)), timestamp: 1819667327, clock: EventClock(1 => 1014295135892017153, 50 => 1014310498059100224), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667327 }) }; event clock=EventClock(1 => 1014295135892017153, 50 => 1014310498059100224) my clock=EventClock(1 => 1014295135892017153, 50 => 1014310498059100224)
1788127853.137 sable_history  #x1B[2m2027-08-30T23:08:47.717348Z#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
1788127853.234 1: waiting…
1788127853.240 sable_ircd     #x1B[2m2026-08-30T22:10:53.239589Z#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
1788127853.240 S -> 1: :My.Little.Server PONG My.Little.Server :foo
1788127853.637 sable_history  #x1B[2m2027-08-30T23:27:46.047744Z#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(1014315524227801174)), timestamp: 1819668466, clock: EventClock(1 => 1014295552021499922, 50 => 1014315272565366869, 99 => 1014295555868078086), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819668466 }) }; event clock=EventClock(1 => 1014295552021499922, 50 => 1014315272565366869, 99 => 1014295555868078086) my clock=EventClock(1 => 1014295552021499922, 50 => 1014315272565366869, 99 => 1014295555868078086)
1788127853.637 sable_history  #x1B[2m2027-08-30T23:27:46.049200Z#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
1788127853.756 sable_history  #x1B[2m2027-08-30T23:08:48.192150Z#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(1014310751713828929)), timestamp: 1819667328, clock: EventClock(1 => 1014295138538622977, 50 => 1014310500051394624), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667328 }) }; event clock=EventClock(1 => 1014295138538622977, 50 => 1014310500051394624) my clock=EventClock(1 => 1014295138538622977, 50 => 1014310500051394624)
1788127853.756 sable_history  #x1B[2m2027-08-30T23:08:48.193907Z#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
1788127853.964 sable_history  #x1B[2m2027-08-30T23:14:24.671293Z#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(1014312163009044552)), timestamp: 1819667664, clock: EventClock(1 => 1014295541992919062, 50 => 1014311911350804551, 99 => 1014295545160019972), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667664 }) }; event clock=EventClock(1 => 1014295541992919062, 50 => 1014311911350804551, 99 => 1014295545160019972) my clock=EventClock(1 => 1014295541992919062, 50 => 1014311911350804551, 99 => 1014295545160019972)
1788127853.964 sable_history  #x1B[2m2027-08-30T23:14:24.672681Z#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
1788127854.241 1: waiting…
1788127854.243 sable_ircd     #x1B[2m2026-08-30T22:10:54.243220Z#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 :synchronize498.354121889
1788127854.244 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize498.354121889
1788127854.313 sable_history  #x1B[2m2027-08-30T23:08:48.942747Z#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(1014310754859556929)), timestamp: 1819667328, clock: EventClock(1 => 1014295140782575617, 50 => 1014310503201316928), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667328 }) }; event clock=EventClock(1 => 1014295140782575617, 50 => 1014310503201316928) my clock=EventClock(1 => 1014295140782575617, 50 => 1014310503201316928)
1788127854.313 sable_history  #x1B[2m2027-08-30T23:08:48.944689Z#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
1788127854.700 sable_history  #x1B[2m2027-08-30T23:17:14.293760Z#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(1014312874459471947)), timestamp: 1819667834, clock: EventClock(1 => 1014295494442094614, 50 => 1014312622801231946, 99 => 1014295497634361348), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667834 }) }; event clock=EventClock(1 => 1014295494442094614, 50 => 1014312622801231946, 99 => 1014295497634361348) my clock=EventClock(1 => 1014295494442094614, 50 => 1014312622801231946, 99 => 1014295497634361348)
1788127854.700 sable_history  #x1B[2m2027-08-30T23:17:14.295249Z#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
1788127854.746 sable_history  #x1B[2m2027-08-30T23:08:49.167674Z#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(1014310755807469633)), timestamp: 1819667329, clock: EventClock(1 => 1014295142766481409, 50 => 1014310504149229632), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667329 }) }; event clock=EventClock(1 => 1014295142766481409, 50 => 1014310504149229632) my clock=EventClock(1 => 1014295142766481409, 50 => 1014310504149229632)
1788127854.746 sable_history  #x1B[2m2027-08-30T23:08:49.169095Z#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
1788127854.945 1 -> S: @label=abc NICK alice2
1788127854.945 sable_ircd     #x1B[2m2026-08-30T22:10:54.945000Z#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
1788127854.945 sable_ircd     #x1B[2m2026-08-30T22:10:54.945062Z#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=abc NICK alice2"
1788127855.045 1 -> S: PING synchronize501.166288052
1788127855.045 sable_ircd     #x1B[2m2026-08-30T22:10:55.045193Z#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
1788127855.045 sable_ircd     #x1B[2m2026-08-30T22:10:55.045251Z#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 synchronize501.166288052"
1788127855.046 sable_ircd     #x1B[2m2026-08-30T22:10:55.045645Z#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(882024613339467779)), timestamp: 1788127855, clock: EventClock(1 => 882024601117265922), target: Nickname(NicknameId(Nickname("alice2"))), details: BindNickname(BindNickname { user: UserId(Snowflake(882024601117265920)) }) }; event clock=EventClock(1 => 882024601117265922) my clock=EventClock(1 => 882024601117265922)
1788127855.046 sable_ircd     #x1B[2m2026-08-30T22:10:55.045778Z#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
1788127855.046 sable_ircd     #x1B[2m2026-08-30T22:10:55.045778Z#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
1788127855.046 sable_ircd     #x1B[2m2026-08-30T22:10:55.046016Z#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(882024613339467779)), timestamp: 1788127855, change: UserNickChange(UserNickChange { user: HistoricUserId(UserId(Snowflake(882024601117265920)), 2), new_nick: Nickname("alice2") }), users_to_notify: [UserId(Snowflake(882024601117265920))] })
1788127855.046 sable_ircd     #x1B[2m2026-08-30T22:10:55.046046Z#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(882024613339467779)), timestamp: 1788127855, change: UserNickChange(UserNickChange { user: HistoricUserId(UserId(Snowflake(882024601117265920)), 2), new_nick: Nickname("alice2") }), users_to_notify: [UserId(Snowflake(882024601117265920))] }
1788127855.046 sable_ircd     #x1B[2m2026-08-30T22:10:55.046110Z#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(882024613339467779)), timestamp: 1788127855, change: EventComplete(EventComplete), users_to_notify: [] })
1788127855.046 sable_ircd     #x1B[2m2026-08-30T22:10:55.046124Z#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(882024613339467779)), timestamp: 1788127855, change: EventComplete(EventComplete), users_to_notify: [] }
1788127855.046 sable_ircd     #x1B[2m2026-08-30T22:10:55.046140Z#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=abc :alice!username@localhost NICK alice2
1788127855.046 S -> 1: @label=abc :alice!username@localhost NICK alice2
1788127855.128 sable_history  #x1B[2m2027-08-30T23:08:49.547982Z#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(1014310757401305153)), timestamp: 1819667329, clock: EventClock(1 => 1014295144268042241, 50 => 1014310505743065152), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667329 }) }; event clock=EventClock(1 => 1014295144268042241, 50 => 1014310505743065152) my clock=EventClock(1 => 1014295144268042241, 50 => 1014310505743065152)
1788127855.128 sable_history  #x1B[2m2027-08-30T23:08:49.549434Z#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
1788127855.632 sable_history  #x1B[2m2027-08-30T23:20:02.100618Z#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(1014313578288848974)), timestamp: 1819668002, clock: EventClock(1 => 1014295448346693654, 50 => 1014313326630608973, 99 => 1014295451547348996), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819668002 }) }; event clock=EventClock(1 => 1014295448346693654, 50 => 1014313326630608973, 99 => 1014295451547348996) my clock=EventClock(1 => 1014295448346693654, 50 => 1014313326630608973, 99 => 1014295451547348996)
1788127855.632 sable_history  #x1B[2m2027-08-30T23:20:02.102070Z#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
1788127855.649 sable_history  #x1B[2m2027-08-30T23:08:50.148049Z#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(1014310759917887554)), timestamp: 1819667330, clock: EventClock(1 => 1014295224354082831, 50 => 1014310508259647553, 99 => 1014295214413983748), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819667330 }) }; event clock=EventClock(1 => 1014295224354082831, 50 => 1014310508259647553, 99 => 1014295214413983748) my clock=EventClock(1 => 1014295224354082831, 50 => 1014310508259647553, 99 => 1014295214413983748)
1788127855.649 sable_history  #x1B[2m2027-08-30T23:08:50.149098Z#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
1788127856.047 1: waiting…
1788127856.052 sable_ircd     #x1B[2m2026-08-30T22:10:56.051514Z#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 :synchronize501.166288052
1788127856.052 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize501.166288052
1788127856.057 1: disconnects from server.