--------------------------------- Captured Out ---------------------------------
---- new test ----
1774773243.381 sable_ircd     uid=1001
1774773243.385 sable_ircd     #x1B[2m2026-03-29T08:34:03.384893Z#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(826011312231288832)), timestamp: 1774773243, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1774773243, name: ServerName("My.Little.Server"), ts: 1774773243, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1774773243.385 sable_ircd     #x1B[2m2026-03-29T08:34:03.385022Z#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
1774773243.385 sable_ircd     #x1B[2m2026-03-29T08:34:03.385036Z#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
1774773243.385 sable_ircd     #x1B[2m2026-03-29T08:34:03.385242Z#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(826011312231288832)), timestamp: 1774773243, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1774773243, name: ServerName("My.Little.Server"), ts: 1774773243, 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
1774773243.385 sable_ircd     #x1B[2m2026-03-29T08:34:03.385361Z#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(826011312231288832)), timestamp: 1774773243, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1774773243.385 sable_ircd     #x1B[2m2026-03-29T08:34:03.385400Z#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(826011312231288832)), timestamp: 1774773243, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1774773243.385 sable_ircd     #x1B[2m2026-03-29T08:34:03.385456Z#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(826011312231288832)), timestamp: 1774773243, change: EventComplete(EventComplete), users_to_notify: [] })
1774773243.386 sable_ircd     #x1B[2m2026-03-29T08:34:03.385483Z#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(826011312231288832)), timestamp: 1774773243, change: EventComplete(EventComplete), users_to_notify: [] }
1774773243.387 sable_ircd     #x1B[2m2026-03-29T08:34:03.386511Z#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(826011312239677441)), timestamp: 1774773243, clock: EventClock(1 => 826011312231288832), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1774773243 }) }; event clock=EventClock(1 => 826011312231288832) my clock=EventClock(1 => 826011312231288832)
1774773243.387 sable_ircd     #x1B[2m2026-03-29T08:34:03.386574Z#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
1774773243.387 sable_ircd     #x1B[2m2026-03-29T08:34:03.386800Z#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(826011312239677441)), timestamp: 1774773243, change: EventComplete(EventComplete), users_to_notify: [] })
1774773243.387 sable_ircd     #x1B[2m2026-03-29T08:34:03.386833Z#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(826011312239677441)), timestamp: 1774773243, change: EventComplete(EventComplete), users_to_notify: [] }
1774773243.477 sable_ircd     #x1B[2m2026-03-29T08:34:03.477041Z#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 }
1774773243.477 sable_ircd     #x1B[2m2026-03-29T08:34:03.477220Z#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
1774773243.477 sable_ircd     #x1B[2m2026-03-29T08:34:03.477300Z#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
1774773243.477 sable_ircd     #x1B[2m2026-03-29T08:34:03.477367Z#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
1774773243.478 sable_ircd     #x1B[2m2026-03-29T08:34:03.477529Z#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
1774773243.478 sable_ircd     #x1B[2m2026-03-29T08:34:03.477597Z#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"
1774773243.478 sable_ircd     #x1B[2m2026-03-29T08:34:03.477751Z#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
1774773243.478 sable_ircd     #x1B[2m2026-03-29T08:34:03.477864Z#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))
1774773243.478 sable_ircd     #x1B[2m2026-03-29T08:34:03.477904Z#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)
1774773243.478 sable_ircd     #x1B[2m2026-03-29T08:34:03.478370Z#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
1774773243.478 sable_ircd     #x1B[2m2026-03-29T08:34:03.478450Z#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
1774773243.479 sable_ircd     #x1B[2m2026-03-29T08:34:03.478686Z#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
1774773243.498 1: connects to server.
1774773243.498 1 -> S: CAP LS 302
1774773243.499 sable_ircd     #x1B[2m2026-03-29T08:34:03.498564Z#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 }
1774773243.499 sable_ircd     #x1B[2m2026-03-29T08:34:03.498728Z#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
1774773243.499 sable_ircd     #x1B[2m2026-03-29T08:34:03.498782Z#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
1774773243.499 sable_ircd     #x1B[2m2026-03-29T08:34:03.498865Z#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
1774773243.499 sable_ircd     #x1B[2m2026-03-29T08:34:03.499076Z#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
1774773243.499 sable_ircd     #x1B[2m2026-03-29T08:34:03.499144Z#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"
1774773243.499 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1774773243.499 sable_ircd     #x1B[2m2026-03-29T08:34:03.499278Z#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
1774773243.500 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
1774773243.500 1 -> S: CAP REQ :batch labeled-response
1774773243.500 sable_ircd     #x1B[2m2026-03-29T08:34:03.499971Z#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
1774773243.500 sable_ircd     #x1B[2m2026-03-29T08:34:03.500031Z#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"))
1774773243.500 sable_ircd     #x1B[2m2026-03-29T08:34:03.500084Z#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
1774773243.500 sable_ircd     #x1B[2m2026-03-29T08:34:03.500131Z#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: 547, tv_nsec: 717205308 }, 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
1774773243.500 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost1774773243.500 sable_ircd     #x1B[2m2026-03-29T08:34:03.500396Z#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

1774773243.501 sable_ircd     #x1B[2m2026-03-29T08:34:03.500454Z#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"
1774773243.501 sable_ircd     #x1B[2m2026-03-29T08:34:03.500613Z#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
1774773243.501 sable_ircd     #x1B[2m2026-03-29T08:34:03.500683Z#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))
1774773243.541 S -> 1: :My.Little.Server CAP * ACK :batch labeled-response
1774773243.541 1 -> S: NICK alice
1774773243.541 1 -> S: USER username * * :Realname
1774773243.541 1 -> S: CAP END
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.541478Z#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
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.541557Z#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"
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.541765Z#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: 547, tv_nsec: 717205308 }, 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
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.541845Z#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
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.541877Z#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
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.541906Z#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"
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.541973Z#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: 547, tv_nsec: 717205308 }, 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
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.542025Z#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
1774773243.542 sable_ircd     #x1B[2m2026-03-29T08:34:03.542057Z#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"
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.044417Z#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: 547, tv_nsec: 717205308 }, 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
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.044581Z#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))
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.044959Z#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(826011314999529474)), timestamp: 1774773244, clock: EventClock(1 => 826011312239677441), target: User(UserId(Snowflake(826011314999529472))), 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(826011314999529473)), NewUserConnection { user: UserId(Snowflake(826011314999529472)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1774773244 })) }) }; event clock=EventClock(1 => 826011312239677441) my clock=EventClock(1 => 826011312239677441)
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.045067Z#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
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.045315Z#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(826011314999529474)), timestamp: 1774773244, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826011314999529472)), 1) }), users_to_notify: [] })
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.045347Z#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(826011314999529474)), timestamp: 1774773244, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826011314999529472)), 1) }), users_to_notify: [] }
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.045395Z#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(826011314999529474)), timestamp: 1774773244, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826011314999529472)), 2), connection: UserConnectionId(Snowflake(826011314999529473)) }), users_to_notify: [] })
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.045418Z#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(826011314999529474)), timestamp: 1774773244, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826011314999529472)), 2), connection: UserConnectionId(Snowflake(826011314999529473)) }), users_to_notify: [] }
1774773244.045 sable_ircd     #x1B[2m2026-03-29T08:34:04.045466Z#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
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045501Z#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
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045523Z#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-03-29 08:34:04.045514888 UTC
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045541Z#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
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045599Z#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
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045659Z#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
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045693Z#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 +
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045722Z#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.
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045783Z#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(826011314999529474)), timestamp: 1774773244, change: EventComplete(EventComplete), users_to_notify: [] })
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.045822Z#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(826011314999529474)), timestamp: 1774773244, change: EventComplete(EventComplete), users_to_notify: [] }
1774773244.046 S -> 1: :My.Little.Server 001 alice :Welcome to the test Internet Relay Chat network, alice
1774773244.046 1 -> S: PING foo
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.046344Z#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
1774773244.046 sable_ircd     #x1B[2m2026-03-29T08:34:04.046395Z#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"
1774773244.146 1 -> S: PING synchronize548.36456617
1774773244.146 S -> 1: :My.Little.Server 002 alice :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1774773244.146 S -> 1: :My.Little.Server 003 alice :This server was created 2026-03-29 08:34:04.045514888 UTC
1774773244.146 S -> 1: :My.Little.Server 004 alice My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1774773244.146 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
1774773244.146 S -> 1: :My.Little.Server 422 alice :MOTD File is missing
1774773244.146 S -> 1: :alice!username@localhost MODE alice +
1774773244.146 S -> 1: :My.Little.Server NOTICE alice :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1774773244.147 sable_ircd     #x1B[2m2026-03-29T08:34:04.146847Z#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
1774773244.147 sable_ircd     #x1B[2m2026-03-29T08:34:04.146916Z#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 synchronize548.36456617"
1774773244.279 sable_history  #x1B[2m2027-03-29T09:57:11.125098Z#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(958303803273322589)), timestamp: 1806314231, clock: EventClock(1 => 958282148563914785, 50 => 958303551615082588, 99 => 958282152058171397), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314231 }) }; event clock=EventClock(1 => 958282148563914785, 50 => 958303551615082588, 99 => 958282152058171397) my clock=EventClock(1 => 958282148563914785, 50 => 958303551615082588, 99 => 958282152058171397)
1774773244.279 sable_history  #x1B[2m2027-03-29T09:57:11.126856Z#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
1774773244.477 sable_history  #x1B[2m2027-03-29T09:54:23.302605Z#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(958303099372642393)), timestamp: 1806314063, clock: EventClock(1 => 958281444919087105, 50 => 958302847718596696), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314063 }) }; event clock=EventClock(1 => 958281444919087105, 50 => 958302847718596696) my clock=EventClock(1 => 958281444919087105, 50 => 958302847718596696)
1774773244.477 sable_history  #x1B[2m2027-03-29T09:54:23.304224Z#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
1774773244.926 sable_history  #x1B[2m2027-03-29T09:54:23.752901Z#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(958303101264273497)), timestamp: 1806314063, clock: EventClock(1 => 958281446718443521, 50 => 958302849610227800), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314063 }) }; event clock=EventClock(1 => 958281446718443521, 50 => 958302849610227800) my clock=EventClock(1 => 958281446718443521, 50 => 958302849610227800)
1774773244.926 sable_history  #x1B[2m2027-03-29T09:54:23.754503Z#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
1774773245.148 1: waiting…
1774773245.151 sable_ircd     #x1B[2m2026-03-29T08:34:05.151367Z#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
1774773245.152 S -> 1: :My.Little.Server PONG My.Little.Server :foo
1774773245.345 sable_history  #x1B[2m2027-03-29T10:00:00.125016Z#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(958304512110698592)), timestamp: 1806314400, clock: EventClock(1 => 958281851250675734, 50 => 958304260452458591, 99 => 958281854329696260), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314400 }) }; event clock=EventClock(1 => 958281851250675734, 50 => 958304260452458591, 99 => 958281854329696260) my clock=EventClock(1 => 958281851250675734, 50 => 958304260452458591, 99 => 958281854329696260)
1774773245.346 sable_history  #x1B[2m2027-03-29T10:00:00.126607Z#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
1774773245.374 sable_history  #x1B[2m2027-03-29T09:54:24.174741Z#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(958303103034269785)), timestamp: 1806314064, clock: EventClock(1 => 958281448605880321, 50 => 958302851371835480), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314064 }) }; event clock=EventClock(1 => 958281448605880321, 50 => 958302851371835480) my clock=EventClock(1 => 958281448605880321, 50 => 958302851371835480)
1774773245.374 sable_history  #x1B[2m2027-03-29T09:54:24.176237Z#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
1774773245.863 sable_history  #x1B[2m2027-03-29T09:54:24.622311Z#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(958303104909123673)), timestamp: 1806314064, clock: EventClock(1 => 958281450614951937, 50 => 958302853250883672), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314064 }) }; event clock=EventClock(1 => 958281450614951937, 50 => 958302853250883672) my clock=EventClock(1 => 958281450614951937, 50 => 958302853250883672)
1774773245.863 sable_history  #x1B[2m2027-03-29T09:54:24.624133Z#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
1774773245.968 sable_history  #x1B[2m2027-03-29T10:02:48.795303Z#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(958305219563954275)), timestamp: 1806314568, clock: EventClock(1 => 958281803347529750, 50 => 958304967905714274, 99 => 958281806971809796), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314568 }) }; event clock=EventClock(1 => 958281803347529750, 50 => 958304967905714274, 99 => 958281806971809796) my clock=EventClock(1 => 958281803347529750, 50 => 958304967905714274, 99 => 958281806971809796)
1774773245.968 sable_history  #x1B[2m2027-03-29T10:02:48.796720Z#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
1774773246.153 1: waiting…
1774773246.157 sable_ircd     #x1B[2m2026-03-29T08:34:06.157021Z#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 :synchronize548.36456617
1774773246.157 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize548.36456617
1774773246.378 sable_history  #x1B[2m2027-03-29T09:54:25.472890Z#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(958303108478476377)), timestamp: 1806314065, clock: EventClock(1 => 958281460278628355, 50 => 958302856820236376), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314065 }) }; event clock=EventClock(1 => 958281460278628355, 50 => 958302856820236376) my clock=EventClock(1 => 958281460278628355, 50 => 958302856820236376)
1774773246.378 sable_history  #x1B[2m2027-03-29T09:54:25.475050Z#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
1774773246.843 sable_history  #x1B[2m2027-03-29T10:05:44.441199Z#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(958305956276674662)), timestamp: 1806314744, clock: EventClock(1 => 958281754748129302, 50 => 958305704622628965, 99 => 958281768291938308), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314744 }) }; event clock=EventClock(1 => 958281754748129302, 50 => 958305704622628965, 99 => 958281768291938308) my clock=EventClock(1 => 958281754748129302, 50 => 958305704622628965, 99 => 958281768291938308)
1774773246.843 sable_history  #x1B[2m2027-03-29T10:05:44.442670Z#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
1774773246.853 sable_history  #x1B[2m2027-03-29T09:54:25.668108Z#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(958303109296365658)), timestamp: 1806314065, clock: EventClock(1 => 958281533112717327, 50 => 958302857642319961, 99 => 958281523239727108), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1806314065 }) }; event clock=EventClock(1 => 958281533112717327, 50 => 958302857642319961, 99 => 958281523239727108) my clock=EventClock(1 => 958281533112717327, 50 => 958302857642319961, 99 => 958281523239727108)
1774773246.853 sable_history  #x1B[2m2027-03-29T09:54:25.669302Z#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
1774773246.858 1 -> S: @label=abc NICK alice2
1774773246.859 sable_ircd     #x1B[2m2026-03-29T08:34:06.858871Z#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
1774773246.859 sable_ircd     #x1B[2m2026-03-29T08:34:06.858948Z#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"
1774773246.959 1 -> S: PING synchronize551.176857749
1774773246.959 sable_ircd     #x1B[2m2026-03-29T08:34:06.959018Z#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
1774773246.959 sable_ircd     #x1B[2m2026-03-29T08:34:06.959090Z#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 synchronize551.176857749"
1774773247.211 sable_ircd     #x1B[2m2026-03-29T08:34:07.211012Z#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(826011328278695939)), timestamp: 1774773247, clock: EventClock(1 => 826011314999529474), target: Nickname(NicknameId(Nickname("alice2"))), details: BindNickname(BindNickname { user: UserId(Snowflake(826011314999529472)) }) }; event clock=EventClock(1 => 826011314999529474) my clock=EventClock(1 => 826011314999529474)
1774773247.211 sable_ircd     #x1B[2m2026-03-29T08:34:07.211180Z#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
1774773247.211 sable_ircd     #x1B[2m2026-03-29T08:34:07.211194Z#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
1774773247.212 sable_ircd     #x1B[2m2026-03-29T08:34:07.211577Z#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(826011328278695939)), timestamp: 1774773247, change: UserNickChange(UserNickChange { user: HistoricUserId(UserId(Snowflake(826011314999529472)), 2), new_nick: Nickname("alice2") }), users_to_notify: [UserId(Snowflake(826011314999529472))] })
1774773247.212 sable_ircd     #x1B[2m2026-03-29T08:34:07.211623Z#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(826011328278695939)), timestamp: 1774773247, change: UserNickChange(UserNickChange { user: HistoricUserId(UserId(Snowflake(826011314999529472)), 2), new_nick: Nickname("alice2") }), users_to_notify: [UserId(Snowflake(826011314999529472))] }
1774773247.212 sable_ircd     #x1B[2m2026-03-29T08:34:07.211750Z#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(826011328278695939)), timestamp: 1774773247, change: EventComplete(EventComplete), users_to_notify: [] })
1774773247.212 sable_ircd     #x1B[2m2026-03-29T08:34:07.211782Z#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(826011328278695939)), timestamp: 1774773247, change: EventComplete(EventComplete), users_to_notify: [] }
1774773247.212 sable_ircd     #x1B[2m2026-03-29T08:34:07.211811Z#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
1774773247.212 S -> 1: @label=abc :alice!username@localhost NICK alice2
1774773248.213 1: waiting…
1774773248.218 sable_ircd     #x1B[2m2026-03-29T08:34:08.217434Z#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 :synchronize551.176857749
1774773248.218 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize551.176857749
1774773248.220 1: disconnects from server.