--------------------------------- Captured Out ---------------------------------
---- new test ----
1772747646.153 sable_ircd     uid=1001
1772747646.154 sable_services uid=1001
1772747646.155 sable_services #x1B[2m2026-03-05T21:54:06.154676Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.History"), address: "127.0.0.1:45257", fingerprint: "6f5868b61942054adcad00dd02ee4349e8ae91f0" }
1772747646.155 sable_services #x1B[2m2026-03-05T21:54:06.155002Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Could not connect to 127.0.0.1:45257. Last error: I/O error: Connection refused (os error 111)
1772747646.155 sable_services #x1B[2m2026-03-05T21:54:06.155048Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mI/O error: Connection refused (os error 111)
1772747646.158 sable_ircd     #x1B[2m2026-03-05T21:54:06.158062Z#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(817515341683888128)), timestamp: 1772747646, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Server"), ts: 1772747646, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1772747646.158 sable_ircd     #x1B[2m2026-03-05T21:54:06.158189Z#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
1772747646.159 sable_ircd     #x1B[2m2026-03-05T21:54:06.158460Z#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(817515341683888128)), timestamp: 1772747646, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Server"), ts: 1772747646, 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
1772747646.159 sable_ircd     #x1B[2m2026-03-05T21:54:06.158697Z#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(817515341683888128)), timestamp: 1772747646, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1772747646.159 sable_ircd     #x1B[2m2026-03-05T21:54:06.158740Z#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(817515341683888128)), timestamp: 1772747646, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1772747646.159 sable_ircd     #x1B[2m2026-03-05T21:54:06.158796Z#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(817515341683888128)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] })
1772747646.159 sable_ircd     #x1B[2m2026-03-05T21:54:06.158824Z#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(817515341683888128)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] }
1772747646.160 sable_ircd     #x1B[2m2026-03-05T21:54:06.159546Z#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(817515341688082433)), timestamp: 1772747646, clock: EventClock(1 => 817515341683888128), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1772747646 }) }; event clock=EventClock(1 => 817515341683888128) my clock=EventClock(1 => 817515341683888128)
1772747646.160 sable_ircd     #x1B[2m2026-03-05T21:54:06.159619Z#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
1772747646.160 sable_ircd     #x1B[2m2026-03-05T21:54:06.159851Z#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(817515341688082433)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] })
1772747646.160 sable_ircd     #x1B[2m2026-03-05T21:54:06.159886Z#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(817515341688082433)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] }
1772747646.160 sable_ircd     #x1B[2m2026-03-05T21:54:06.160035Z#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
1772747646.248 sable_ircd     #x1B[2m2026-03-05T21:54:06.247883Z#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 }
1772747646.248 sable_ircd     #x1B[2m2026-03-05T21:54:06.248016Z#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
1772747646.248 sable_ircd     #x1B[2m2026-03-05T21:54:06.248077Z#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
1772747646.248 sable_ircd     #x1B[2m2026-03-05T21:54:06.248166Z#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
1772747646.248 sable_ircd     #x1B[2m2026-03-05T21:54:06.248383Z#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
1772747646.249 sable_ircd     #x1B[2m2026-03-05T21:54:06.248451Z#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"
1772747646.249 sable_ircd     #x1B[2m2026-03-05T21:54:06.248580Z#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
1772747646.249 sable_ircd     #x1B[2m2026-03-05T21:54:06.248672Z#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))
1772747646.249 sable_ircd     #x1B[2m2026-03-05T21:54:06.248707Z#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)
1772747646.249 sable_ircd     #x1B[2m2026-03-05T21:54:06.249177Z#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
1772747646.249 sable_ircd     #x1B[2m2026-03-05T21:54:06.249275Z#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
1772747646.250 sable_ircd     #x1B[2m2026-03-05T21:54:06.249503Z#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
1772747646.267 sable_services #x1B[2m2026-03-05T21:54:06.266965Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.Server"), address: "127.0.0.1:43089", fingerprint: "635e079ff631d7ad935e30bb536e25288db828e4" }
1772747646.267 sable_services #x1B[2m2026-03-05T21:54:06.267173Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747646.269 chkSvs: connects to server.
1772747646.269 chkSvs -> S: NICK chkSvs
1772747646.269 chkSvs -> S: USER chk chk chk chk
1772747646.270 sable_ircd     #x1B[2m2026-03-05T21:54:06.269506Z#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 }
1772747646.270 sable_ircd     #x1B[2m2026-03-05T21:54:06.269612Z#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
1772747646.270 sable_ircd     #x1B[2m2026-03-05T21:54:06.269655Z#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
1772747646.270 sable_ircd     #x1B[2m2026-03-05T21:54:06.269704Z#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
1772747646.270 sable_ircd     #x1B[2m2026-03-05T21:54:06.269816Z#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
1772747646.270 sable_ircd     #x1B[2m2026-03-05T21:54:06.269889Z#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 chkSvs"
1772747646.270 sable_ircd     #x1B[2m2026-03-05T21:54:06.270050Z#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: 483, tv_nsec: 606356335 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(<uninit>), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747646.270 sable_ircd     #x1B[2m2026-03-05T21:54:06.270156Z#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
1772747646.271 sable_ircd     #x1B[2m2026-03-05T21:54:06.270254Z#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
1772747646.271 sable_ircd     #x1B[2m2026-03-05T21:54:06.270311Z#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 chk chk chk chk"
1772747646.271 sable_ircd     #x1B[2m2026-03-05T21:54:06.270472Z#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: 483, tv_nsec: 606356335 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747646.271 sable_ircd     #x1B[2m2026-03-05T21:54:06.271407Z#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
1772747646.271 sable_ircd     #x1B[2m2026-03-05T21:54:06.271462Z#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"))
1772747646.272 sable_ircd     #x1B[2m2026-03-05T21:54:06.271509Z#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
1772747646.272 sable_ircd     #x1B[2m2026-03-05T21:54:06.271548Z#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: 483, tv_nsec: 606356335 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747646.272 sable_ircd     #x1B[2m2026-03-05T21:54:06.271623Z#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))
1772747646.272 sable_ircd     #x1B[2m2026-03-05T21:54:06.271936Z#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(817515342157844482)), timestamp: 1772747646, clock: EventClock(1 => 817515341688082433), target: User(UserId(Snowflake(817515342157844480))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515342157844481)), NewUserConnection { user: UserId(Snowflake(817515342157844480)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747646 })) }) }; event clock=EventClock(1 => 817515341688082433) my clock=EventClock(1 => 817515341688082433)
1772747646.272 sable_ircd     #x1B[2m2026-03-05T21:54:06.272036Z#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
1772747646.272 sable_ircd     #x1B[2m2026-03-05T21:54:06.272360Z#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(817515342157844482)), timestamp: 1772747646, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515342157844480)), 1) }), users_to_notify: [] })
1772747646.272 sable_ircd     #x1B[2m2026-03-05T21:54:06.272400Z#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(817515342157844482)), timestamp: 1772747646, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515342157844480)), 1) }), users_to_notify: [] }
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272459Z#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(817515342157844482)), timestamp: 1772747646, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515342157844480)), 2), connection: UserConnectionId(Snowflake(817515342157844481)) }), users_to_notify: [] })
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272503Z#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(817515342157844482)), timestamp: 1772747646, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515342157844480)), 2), connection: UserConnectionId(Snowflake(817515342157844481)) }), users_to_notify: [] }
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272580Z#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 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272617Z#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 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272646Z#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 chkSvs :This server was created 2026-03-05 21:54:06.272634950 UTC
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272672Z#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 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272720Z#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 chkSvs 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
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272750Z#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 chkSvs :MOTD File is missing
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272779Z#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): :chkSvs!chk@localhost MODE chkSvs +
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272806Z#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 chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272870Z#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(817515342157844482)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] })
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.272903Z#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(817515342157844482)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] }
1772747646.273 sable_ircd     #x1B[2m2026-03-05T21:54:06.273224Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::node#x1B[0m#x1B[2m:#x1B[0m Server got state export request; sending
1772747646.316 sable_services #x1B[2m2026-03-05T21:54:06.316078Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Bootstrap message: Message { source_server: (ServerId(1), 1772747646), content: NetworkState(Network { nick_bindings: {Nickname("chkSvs"): NickBinding { nick: Nickname("chkSvs"), user: UserId(Snowflake(817515342157844480)), timestamp: 1772747646, created: EventId(Snowflake(817515342157844482)) }}, historic_nick_users: HistoricNickStore { data: {} }, users: {UserId(Snowflake(817515342157844480)): User { id: UserId(Snowflake(817515342157844480)), serial: 2, user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, mode: UserMode { modes: UserModeSet(0) }, oper_privileges: None, account: None, session_key: None }}, historic_users: HistoricUserStore { users: {HistoricUserId(UserId(Snowflake(817515342157844480)), 1): HistoricUser { id: UserId(Snowflake(817515342157844480)), serial: 1, nickname: Nickname("chkSvs"), user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, account: None, timestamp: Some(1772747646) }, HistoricUserId(UserId(Snowflake(817515342157844480)), 2): HistoricUser { id: UserId(Snowflake(817515342157844480)), serial: 2, nickname: Nickname("chkSvs"), user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, account: None, timestamp: None }} }, user_connections: {UserConnectionId(Snowflake(817515342157844481)): UserConnection { id: UserConnectionId(Snowflake(817515342157844481)), user: UserId(Snowflake(817515342157844480)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747646 }}, channels: {}, list_mode_entries: {}, channel_topics: {}, channel_invites: {}, memberships: {}, messages: {}, servers: {ServerId(1): Server { id: ServerId(1), epoch: 1772747646, name: ServerName("My.Little.Server"), last_ping: 1772747646, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }}, network_bans: BanRepository { pre_registration_bans: {}, new_connection_bans: {}, pre_sasl_bans: {}, pre_registration_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: ["", "", "", "", "", ""], uint64: [], regex: [] }, fields: {"user": (3, String(_)), "user_param_2": (0, String(_)), "tls": (0, Boolean(_)), "user_param_1": (1, String(_)), "host": (5, String(_)), "realname": (4, String(_)), "nick": (2, String(_)), "ip": (0, Ip(_))} }, new_connection_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [], uint64: [], regex: [] }, fields: {"tls": (0, Boolean(_)), "ip": (0, Ip(_))} }, pre_sasl_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [""], uint64: [], regex: [] }, fields: {"tls": (0, Boolean(_)), "ip": (0, Ip(_)), "mechanism": (0, String(_))} } }, audit_log: {}, accounts: {}, nick_registrations: {}, channel_registrations: {}, channel_accesses: {}, channel_roles: {}, current_services: None, current_history_server_id: None, config: NetworkConfig { opers: [OperConfig { name: "operuser", hash: "$6$z5yA.OfGliDoi/R2$BgSsguS6bxAsPSCygDisgDw5JZuo5.88eU3Hyc7/4OaNpeKIxWGjOggeHzOl0xLiZg1vfwxXjOTFN14wG5vNI." }], debug_mode: true, default_roles: {BuiltinVoice: ChannelAccessSet(4296081441), BuiltinOp: ChannelAccessSet(222192934775), BuiltinAll: ChannelAccessSet(1114112)}, alias_users: [AliasUser { nick: Nickname("ChanServ"), user: Username("ChanServ"), host: Hostname("services."), realname: Realname("Channel services compatibility layer"), command_alias: "CS" }, AliasUser { nick: Nickname("NickServ"), user: Username("NickServ"), host: Hostname("services."), realname: Realname("Account services compatibility layer"), command_alias: "NS" }], object_expiry: 60, pingout_duration: 600 }, clock: EventClock(1 => 817515342157844482), cache_default_channel_roles: OnceLock(<uninit>), alias_users: OnceLock(<uninit>) }) }
1772747646.316 sable_services #x1B[2m2026-03-05T21:54:06.316261Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Server
1772747646.316 sable_services #x1B[2m2026-03-05T21:54:06.316397Z#x1B[0m #x1B[33m WARN#x1B[0m #x1B[2msable_services::database::jsonfile#x1B[0m#x1B[2m:#x1B[0m Couldn't open database file, starting from empty
1772747646.317 sable_services #x1B[2m2026-03-05T21:54:06.316875Z#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(817515342346989568)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Services"), ts: 1772747646, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 817515342157844482) my clock=EventClock(1 => 817515342157844482)
1772747646.317 sable_services #x1B[2m2026-03-05T21:54:06.317231Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342346989568)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Services"), ts: 1772747646, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747646.319 sable_services #x1B[2m2026-03-05T21:54:06.319003Z#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(817515342355378177)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342346989568), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747646 }) }; event clock=EventClock(1 => 817515342157844482, 99 => 817515342346989568) my clock=EventClock(1 => 817515342157844482, 99 => 817515342346989568)
1772747646.320 sable_services #x1B[2m2026-03-05T21:54:06.319306Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342355378177)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342346989568), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747646 }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747646.320 sable_services #x1B[2m2026-03-05T21:54:06.319421Z#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(817515342346989568)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Services"), ts: 1772747646, 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(99) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Services
1772747646.323 sable_services #x1B[2m2026-03-05T21:54:06.322928Z#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(817515342372155394)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342355378177), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 817515342157844482, 99 => 817515342355378177) my clock=EventClock(1 => 817515342157844482, 99 => 817515342355378177)
1772747646.324 sable_services #x1B[2m2026-03-05T21:54:06.323230Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342372155394)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342355378177), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747646.324 sable_ircd     #x1B[2m2026-03-05T21:54:06.323881Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342355378177)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342346989568), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747646 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(817515342355378177)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342346989568), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747646 }) }; event clock=EventClock(1 => 817515342157844482, 99 => 817515342346989568) my clock=EventClock(1 => 817515342157844482)
1772747646.324 sable_ircd     #x1B[2m2026-03-05T21:54:06.323940Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342355378177)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342346989568), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747646 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(817515342346989568))]
1772747646.324 sable_ircd     #x1B[2m2026-03-05T21:54:06.323968Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342355378177)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342346989568), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747646 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message
1772747646.360 sable_ircd     #x1B[2m2026-03-05T21:54:06.359714Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342346989568)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Services"), ts: 1772747646, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(817515342346989568)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Services"), ts: 1772747646, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 817515342157844482) my clock=EventClock(1 => 817515342157844482)
1772747646.360 sable_ircd     #x1B[2m2026-03-05T21:54:06.359824Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342346989568)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Services"), ts: 1772747646, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(817515342355378177)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342346989568), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747646 }) }
1772747646.360 sable_ircd     #x1B[2m2026-03-05T21:54:06.359930Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342346989568)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Services"), ts: 1772747646, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message
1772747646.360 sable_ircd     #x1B[2m2026-03-05T21:54:06.360231Z#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(817515342346989568)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747646, name: ServerName("My.Little.Services"), ts: 1772747646, 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(99) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Services
1772747646.361 sable_ircd     #x1B[2m2026-03-05T21:54:06.360486Z#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(817515342346989568)), timestamp: 1772747646, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] })
1772747646.361 sable_ircd     #x1B[2m2026-03-05T21:54:06.360533Z#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(817515342346989568)), timestamp: 1772747646, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] }
1772747646.361 sable_ircd     #x1B[2m2026-03-05T21:54:06.360599Z#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(817515342346989568)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] })
1772747646.361 sable_ircd     #x1B[2m2026-03-05T21:54:06.360638Z#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(817515342346989568)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] }
1772747646.361 sable_ircd     #x1B[2m2026-03-05T21:54:06.360689Z#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(817515342355378177)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] })
1772747646.361 sable_ircd     #x1B[2m2026-03-05T21:54:06.360732Z#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(817515342355378177)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] }
1772747646.365 sable_services #x1B[2m2026-03-05T21:54:06.364563Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: GetEvent([EventId(Snowflake(817515342346989568))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(817515342346989568))]
1772747646.365 sable_services #x1B[2m2026-03-05T21:54:06.364601Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: GetEvent([EventId(Snowflake(817515342346989568))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events []
1772747646.366 sable_ircd     #x1B[2m2026-03-05T21:54:06.365591Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342372155394)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342355378177), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(817515342372155394)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342355378177), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 817515342157844482, 99 => 817515342355378177) my clock=EventClock(1 => 817515342157844482, 99 => 817515342355378177)
1772747646.366 sable_ircd     #x1B[2m2026-03-05T21:54:06.365929Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342372155394)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342355378177), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515342372155394)), timestamp: 1772747646, clock: EventClock(1 => 817515342157844482, 99 => 817515342355378177), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747646.369 S -> chkSvs: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747646.369 S -> chkSvs: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747646.369 S -> chkSvs: :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs
1772747646.369 S -> chkSvs: :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747646.369 S -> chkSvs: :My.Little.Server 003 chkSvs :This server was created 2026-03-05 21:54:06.272634950 UTC
1772747646.369 S -> chkSvs: :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747646.369 S -> chkSvs: :My.Little.Server 005 chkSvs 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
1772747646.369 S -> chkSvs: :My.Little.Server 422 chkSvs :MOTD File is missing
1772747646.369 S -> chkSvs: :chkSvs!chk@localhost MODE chkSvs +
1772747646.369 S -> chkSvs: :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747646.370 chkSvs -> S: CAP LS 302
1772747646.370 sable_ircd     #x1B[2m2026-03-05T21:54:06.369502Z#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(817515342372155394)), timestamp: 1772747646, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] })
1772747646.370 sable_ircd     #x1B[2m2026-03-05T21:54:06.369543Z#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(817515342372155394)), timestamp: 1772747646, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] }
1772747646.370 sable_ircd     #x1B[2m2026-03-05T21:54:06.369655Z#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(817515342372155394)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] })
1772747646.370 sable_ircd     #x1B[2m2026-03-05T21:54:06.369691Z#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(817515342372155394)), timestamp: 1772747646, change: EventComplete(EventComplete), users_to_notify: [] }
1772747646.370 sable_ircd     #x1B[2m2026-03-05T21:54:06.370073Z#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
1772747646.370 sable_ircd     #x1B[2m2026-03-05T21:54:06.370134Z#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"
1772747646.370 sable_ircd     #x1B[2m2026-03-05T21:54:06.370266Z#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 sasl=PLAIN,EXTERNAL 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
1772747646.406 sable_ircd     #x1B[2m2026-03-05T21:54:06.405575Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: BulkEvents([]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: []
1772747646.420 chkSvs -> S: PING synchronize483.756450256
1772747646.420 S -> chkSvs: :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL 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
1772747646.420 sable_ircd     #x1B[2m2026-03-05T21:54:06.420146Z#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
1772747646.420 sable_ircd     #x1B[2m2026-03-05T21:54:06.420195Z#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 synchronize483.756450256"
1772747646.420 sable_ircd     #x1B[2m2026-03-05T21:54:06.420284Z#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 :synchronize483.756450256
1772747646.421 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize483.756450256
1772747646.421 chkSvs -> S: QUIT
1772747646.421 chkSvs -> S: PING synchronize483.757371967
1772747646.421 sable_ircd     #x1B[2m2026-03-05T21:54:06.420983Z#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
1772747646.421 sable_ircd     #x1B[2m2026-03-05T21:54:06.421023Z#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"QUIT"
1772747646.421 sable_ircd     #x1B[2m2026-03-05T21:54:06.421088Z#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
1772747646.421 sable_ircd     #x1B[2m2026-03-05T21:54:06.421120Z#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 synchronize483.757371967"
1772747647.176 sable_ircd     #x1B[2m2026-03-05T21:54:07.175890Z#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): ERROR :Client quit
1772747647.176 sable_ircd     #x1B[2m2026-03-05T21:54:07.176047Z#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), 2))
1772747647.176 sable_ircd     #x1B[2m2026-03-05T21:54:07.176092Z#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), 2)
1772747647.176 S -> chkSvs: ERROR :Client quit
1772747647.176 chkSvs: disconnects from server.
1772747647.177 sable_ircd     #x1B[2m2026-03-05T21:54:07.176261Z#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(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515342157844482, 99 => 817515342372155394) my clock=EventClock(1 => 817515342157844482, 99 => 817515342372155394)
1772747647.177 sable_ircd     #x1B[2m2026-03-05T21:54:07.176539Z#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), 2) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed
1772747647.177 sable_ircd     #x1B[2m2026-03-05T21:54:07.176637Z#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
1772747647.177 sable_ircd     #x1B[2m2026-03-05T21:54:07.176682Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747647.177 1: connects to server.
1772747647.177 1 -> S: NICK jilles
1772747647.177 1 -> S: USER r e g :user
1772747647.177 sable_ircd     #x1B[2m2026-03-05T21:54:07.177094Z#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), 3), remote_addr: 127.0.0.1, tls_info: None }
1772747647.177 sable_ircd     #x1B[2m2026-03-05T21:54:07.177181Z#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
1772747647.177 sable_ircd     #x1B[2m2026-03-05T21:54:07.177227Z#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), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection
1772747647.177 sable_ircd     #x1B[2m2026-03-05T21:54:07.177284Z#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), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server NOTICE * :*** Looking up your hostname
1772747647.178 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747647.178 sable_ircd     #x1B[2m2026-03-05T21:54:07.177781Z#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
1772747647.178 sable_ircd     #x1B[2m2026-03-05T21:54:07.177837Z#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), 3)#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 jilles"
1772747647.178 sable_ircd     #x1B[2m2026-03-05T21:54:07.177982Z#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: 484, tv_nsec: 513931369 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("jilles")), realname: OnceLock(<uninit>), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747647.178 sable_ircd     #x1B[2m2026-03-05T21:54:07.178048Z#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
1772747647.178 sable_ircd     #x1B[2m2026-03-05T21:54:07.178119Z#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
1772747647.178 sable_ircd     #x1B[2m2026-03-05T21:54:07.178168Z#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), 3)#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 r e g :user"
1772747647.178 sable_ircd     #x1B[2m2026-03-05T21:54:07.178265Z#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: 484, tv_nsec: 513931369 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(Nickname("jilles")), realname: OnceLock(Realname("user")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747647.179 sable_ircd     #x1B[2m2026-03-05T21:54:07.178534Z#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
1772747647.179 sable_ircd     #x1B[2m2026-03-05T21:54:07.178566Z#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), 3): 127.0.0.1/Some(Hostname("localhost"))
1772747647.179 sable_ircd     #x1B[2m2026-03-05T21:54:07.178590Z#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), 3): :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747647.179 sable_ircd     #x1B[2m2026-03-05T21:54:07.178611Z#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: 484, tv_nsec: 513931369 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(Nickname("jilles")), realname: OnceLock(Realname("user")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747647.179 sable_ircd     #x1B[2m2026-03-05T21:54:07.178650Z#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), 3))
1772747647.179 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747647.179 sable_ircd     #x1B[2m2026-03-05T21:54:07.179191Z#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(817515345966272516)), timestamp: 1772747647, clock: EventClock(1 => 817515345953689603, 99 => 817515342372155394), target: User(UserId(Snowflake(817515342157844480))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515345953689603, 99 => 817515342372155394) my clock=EventClock(1 => 817515345953689603, 99 => 817515342372155394)
1772747647.180 sable_ircd     #x1B[2m2026-03-05T21:54:07.179476Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345966272516)), timestamp: 1772747647, clock: EventClock(1 => 817515345953689603, 99 => 817515342372155394), target: User(UserId(Snowflake(817515342157844480))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747647.180 sable_ircd     #x1B[2m2026-03-05T21:54:07.179600Z#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(817515345953689603)), timestamp: 1772747647, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515342157844480)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(817515342157844481)), user: UserId(Snowflake(817515342157844480)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747646 } }), users_to_notify: [] })
1772747647.180 sable_ircd     #x1B[2m2026-03-05T21:54:07.179641Z#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(817515345953689603)), timestamp: 1772747647, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515342157844480)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(817515342157844481)), user: UserId(Snowflake(817515342157844480)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747646 } }), users_to_notify: [] }
1772747647.180 sable_ircd     #x1B[2m2026-03-05T21:54:07.179709Z#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(817515345953689603)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] })
1772747647.180 sable_ircd     #x1B[2m2026-03-05T21:54:07.179743Z#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(817515345953689603)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] }
1772747647.183 sable_ircd     #x1B[2m2026-03-05T21:54:07.182966Z#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(817515345978855429)), timestamp: 1772747647, clock: EventClock(1 => 817515345966272516, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515345962078211)), NewUserConnection { user: UserId(Snowflake(817515345962078210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747647 })) }) }; event clock=EventClock(1 => 817515345966272516, 99 => 817515342372155394) my clock=EventClock(1 => 817515345966272516, 99 => 817515342372155394)
1772747647.184 sable_ircd     #x1B[2m2026-03-05T21:54:07.183277Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345978855429)), timestamp: 1772747647, clock: EventClock(1 => 817515345966272516, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515345962078211)), NewUserConnection { user: UserId(Snowflake(817515345962078210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747647 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747647.184 sable_ircd     #x1B[2m2026-03-05T21:54:07.183460Z#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(817515345966272516)), timestamp: 1772747647, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515342157844480)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] })
1772747647.184 sable_ircd     #x1B[2m2026-03-05T21:54:07.183500Z#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(817515345966272516)), timestamp: 1772747647, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515342157844480)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] }
1772747647.184 sable_ircd     #x1B[2m2026-03-05T21:54:07.183569Z#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(817515345966272516)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] })
1772747647.184 sable_ircd     #x1B[2m2026-03-05T21:54:07.183591Z#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(817515345966272516)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] }
1772747647.184 sable_services #x1B[2m2026-03-05T21:54:07.183951Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345966272516)), timestamp: 1772747647, clock: EventClock(1 => 817515345953689603, 99 => 817515342372155394), target: User(UserId(Snowflake(817515342157844480))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(817515345966272516)), timestamp: 1772747647, clock: EventClock(1 => 817515345953689603, 99 => 817515342372155394), target: User(UserId(Snowflake(817515342157844480))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515345953689603, 99 => 817515342372155394) my clock=EventClock(1 => 817515342157844482, 99 => 817515342372155394)
1772747647.184 sable_services #x1B[2m2026-03-05T21:54:07.184020Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345966272516)), timestamp: 1772747647, clock: EventClock(1 => 817515345953689603, 99 => 817515342372155394), target: User(UserId(Snowflake(817515342157844480))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(817515345953689603))]
1772747647.184 sable_services #x1B[2m2026-03-05T21:54:07.184321Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345966272516)), timestamp: 1772747647, clock: EventClock(1 => 817515345953689603, 99 => 817515342372155394), target: User(UserId(Snowflake(817515342157844480))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345966272516)), timestamp: 1772747647, clock: EventClock(1 => 817515345953689603, 99 => 817515342372155394), target: User(UserId(Snowflake(817515342157844480))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185457Z#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(817515345978855429)), timestamp: 1772747647, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 1) }), users_to_notify: [] })
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185511Z#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(817515345978855429)), timestamp: 1772747647, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 1) }), users_to_notify: [] }
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185578Z#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(817515345978855429)), timestamp: 1772747647, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 2), connection: UserConnectionId(Snowflake(817515345962078211)) }), users_to_notify: [] })
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185609Z#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(817515345978855429)), timestamp: 1772747647, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 2), connection: UserConnectionId(Snowflake(817515345962078211)) }), users_to_notify: [] }
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185662Z#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), 3): :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185691Z#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), 3): :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185718Z#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), 3): :My.Little.Server 003 jilles :This server was created 2026-03-05 21:54:07.185707847 UTC
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185743Z#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), 3): :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185769Z#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), 3): :My.Little.Server 005 jilles 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
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185792Z#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), 3): :My.Little.Server 422 jilles :MOTD File is missing
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185821Z#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), 3): :jilles!r@localhost MODE jilles +
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185839Z#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), 3): :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185891Z#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(817515345978855429)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] })
1772747647.186 sable_ircd     #x1B[2m2026-03-05T21:54:07.185922Z#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(817515345978855429)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] }
1772747647.186 S -> 1: :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles
1772747647.186 S -> 1: :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747647.186 S -> 1: :My.Little.Server 003 jilles :This server was created 2026-03-05 21:54:07.185707847 UTC
1772747647.221 sable_services #x1B[2m2026-03-05T21:54:07.220805Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515342157844482, 99 => 817515342372155394) my clock=EventClock(1 => 817515342157844482, 99 => 817515342372155394)
1772747647.221 sable_services #x1B[2m2026-03-05T21:54:07.220908Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(817515345966272516)), timestamp: 1772747647, clock: EventClock(1 => 817515345953689603, 99 => 817515342372155394), target: User(UserId(Snowflake(817515342157844480))), details: UserQuit(UserQuit { message: "Client Quit" }) }
1772747647.221 sable_services #x1B[2m2026-03-05T21:54:07.221274Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747647.225 sable_ircd     #x1B[2m2026-03-05T21:54:07.224555Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: GetEvent([EventId(Snowflake(817515345953689603))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(817515345953689603))]
1772747647.225 sable_ircd     #x1B[2m2026-03-05T21:54:07.224611Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: GetEvent([EventId(Snowflake(817515345953689603))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }]
1772747647.227 sable_services #x1B[2m2026-03-05T21:54:07.226651Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345978855429)), timestamp: 1772747647, clock: EventClock(1 => 817515345966272516, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515345962078211)), NewUserConnection { user: UserId(Snowflake(817515345962078210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747647 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(817515345978855429)), timestamp: 1772747647, clock: EventClock(1 => 817515345966272516, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515345962078211)), NewUserConnection { user: UserId(Snowflake(817515345962078210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747647 })) }) }; event clock=EventClock(1 => 817515345966272516, 99 => 817515342372155394) my clock=EventClock(1 => 817515345966272516, 99 => 817515342372155394)
1772747647.227 sable_services #x1B[2m2026-03-05T21:54:07.226968Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345978855429)), timestamp: 1772747647, clock: EventClock(1 => 817515345966272516, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515345962078211)), NewUserConnection { user: UserId(Snowflake(817515345962078210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747647 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515345978855429)), timestamp: 1772747647, clock: EventClock(1 => 817515345966272516, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515345962078211)), NewUserConnection { user: UserId(Snowflake(817515345962078210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747647 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747647.266 sable_services #x1B[2m2026-03-05T21:54:07.265623Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: BulkEvents([Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(817515345953689603)), timestamp: 1772747647, clock: EventClock(1 => 817515342157844482, 99 => 817515342372155394), target: UserConnection(UserConnectionId(Snowflake(817515342157844481))), details: UserDisconnect(UserDisconnect) }]
1772747647.286 1 -> S: PING synchronize484.623049223
1772747647.286 S -> 1: :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747647.287 S -> 1: :My.Little.Server 005 jilles 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
1772747647.287 S -> 1: :My.Little.Server 422 jilles :MOTD File is missing
1772747647.287 S -> 1: :jilles!r@localhost MODE jilles +
1772747647.287 S -> 1: :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747647.287 sable_ircd     #x1B[2m2026-03-05T21:54:07.286900Z#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
1772747647.287 sable_ircd     #x1B[2m2026-03-05T21:54:07.286961Z#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), 3)#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 synchronize484.623049223"
1772747647.287 sable_ircd     #x1B[2m2026-03-05T21:54:07.287080Z#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), 3): :My.Little.Server PONG My.Little.Server :synchronize484.623049223
1772747647.287 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize484.623049223
1772747647.288 1 -> S: REGISTER * * sesame
1772747647.288 sable_ircd     #x1B[2m2026-03-05T21:54:07.287787Z#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
1772747647.288 sable_ircd     #x1B[2m2026-03-05T21:54:07.287835Z#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), 3)#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"REGISTER * * sesame"
1772747647.288 sable_ircd     #x1B[2m2026-03-05T21:54:07.288069Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Found target peer, sending message #x1B[3mtarget#x1B[0m#x1B[2m=#x1B[0mPeerConfig { name: ServerName("My.Little.Services"), address: "127.0.0.1:44017", fingerprint: "874a0844acad05a0f6e9d427b95c8b987d78ea81" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(RegisterUser(Nickname("jilles"), "sesame")) }
1772747647.288 sable_ircd     #x1B[2m2026-03-05T21:54:07.288312Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(RegisterUser(Nickname("jilles"), "sesame")) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747647.290 sable_ircd     #x1B[2m2026-03-05T21:54:07.289995Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(26) })
1772747647.331 sable_services #x1B[2m2026-03-05T21:54:07.330732Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(RegisterUser(Nickname("jilles"), "sesame"))
1772747647.331 sable_services #x1B[2m2026-03-05T21:54:07.330778Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got register request #x1B[3maccount_name#x1B[0m#x1B[2m=#x1B[0mNickname("jilles")
1772747647.335 sable_services #x1B[2m2026-03-05T21:54:07.334747Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server::command::user_commands#x1B[0m#x1B[2m:#x1B[0m Successfully created account #x1B[3mnew_account#x1B[0m#x1B[2m=#x1B[0mAccount { id: AccountId(Snowflake(817515346600013824)), name: Nickname("jilles"), authorised_fingerprints: [] }
1772747647.335 sable_services #x1B[2m2026-03-05T21:54:07.334945Z#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(817515346616791043)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: Account(AccountId(Snowflake(817515346600013824))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515346600013824)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 817515345978855429, 99 => 817515342372155394) my clock=EventClock(1 => 817515345978855429, 99 => 817515342372155394)
1772747647.335 sable_services #x1B[2m2026-03-05T21:54:07.335255Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515346616791043)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: Account(AccountId(Snowflake(817515346600013824))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515346600013824)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747647.376 sable_ircd     #x1B[2m2026-03-05T21:54:07.375564Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got targeted message response #x1B[3mresponse#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: TargetedMessageResponse(Services(LogUserIn(AccountId(Snowflake(817515346600013824))))) } }
1772747647.376 sable_ircd     #x1B[2m2026-03-05T21:54:07.375819Z#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), 3): REGISTER SUCCESS jilles :You have successfully registered
1772747647.376 sable_ircd     #x1B[2m2026-03-05T21:54:07.375892Z#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
1772747647.376 sable_ircd     #x1B[2m2026-03-05T21:54:07.375948Z#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[0mStateChange(User(UserId(Snowflake(817515345962078210))), UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515346600013824))) }))
1772747647.376 sable_ircd     #x1B[2m2026-03-05T21:54:07.376116Z#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(817515346792550406)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515346600013824))) }) }; event clock=EventClock(1 => 817515345978855429, 99 => 817515342372155394) my clock=EventClock(1 => 817515345978855429, 99 => 817515342372155394)
1772747647.377 sable_ircd     #x1B[2m2026-03-05T21:54:07.376494Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515346792550406)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515346600013824))) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747647.379 sable_ircd     #x1B[2m2026-03-05T21:54:07.378445Z#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(817515346792550406)), timestamp: 1772747647, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 3), old_account: None, new_account: Some(AccountId(Snowflake(817515346600013824))) }), users_to_notify: [] })
1772747647.379 sable_ircd     #x1B[2m2026-03-05T21:54:07.378494Z#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(817515346792550406)), timestamp: 1772747647, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 3), old_account: None, new_account: Some(AccountId(Snowflake(817515346600013824))) }), users_to_notify: [] }
1772747647.379 sable_ircd     #x1B[2m2026-03-05T21:54:07.378555Z#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(817515346792550406)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] })
1772747647.379 sable_ircd     #x1B[2m2026-03-05T21:54:07.378586Z#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(817515346792550406)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] }
1772747647.379 sable_ircd     #x1B[2m2026-03-05T21:54:07.378692Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515346616791043)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: Account(AccountId(Snowflake(817515346600013824))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515346600013824)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(817515346616791043)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: Account(AccountId(Snowflake(817515346600013824))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515346600013824)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 817515345978855429, 99 => 817515342372155394) my clock=EventClock(1 => 817515346792550406, 99 => 817515342372155394)
1772747647.379 sable_ircd     #x1B[2m2026-03-05T21:54:07.379019Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515346616791043)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: Account(AccountId(Snowflake(817515346600013824))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515346600013824)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515346616791043)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: Account(AccountId(Snowflake(817515346600013824))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515346600013824)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747647.381 sable_ircd     #x1B[2m2026-03-05T21:54:07.380839Z#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(817515346616791043)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] })
1772747647.381 sable_ircd     #x1B[2m2026-03-05T21:54:07.380879Z#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(817515346616791043)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] }
1772747647.420 sable_services #x1B[2m2026-03-05T21:54:07.419716Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515346792550406)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515346600013824))) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(817515346792550406)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515346600013824))) }) }; event clock=EventClock(1 => 817515345978855429, 99 => 817515342372155394) my clock=EventClock(1 => 817515345978855429, 99 => 817515346616791043)
1772747647.420 sable_services #x1B[2m2026-03-05T21:54:07.420108Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515346792550406)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515346600013824))) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515346792550406)), timestamp: 1772747647, clock: EventClock(1 => 817515345978855429, 99 => 817515342372155394), target: User(UserId(Snowflake(817515345962078210))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515346600013824))) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747647.488 1 -> S: PING synchronize484.824360689
1772747647.488 S -> 1: REGISTER SUCCESS jilles :You have successfully registered
1772747647.488 sable_ircd     #x1B[2m2026-03-05T21:54:07.488205Z#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
1772747647.488 sable_ircd     #x1B[2m2026-03-05T21:54:07.488269Z#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), 3)#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 synchronize484.824360689"
1772747648.241 sable_ircd     #x1B[2m2026-03-05T21:54:08.241142Z#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), 3): :My.Little.Server PONG My.Little.Server :synchronize484.824360689
1772747648.241 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize484.824360689
1772747648.242 1 -> S: QUIT
1772747648.242 sable_ircd     #x1B[2m2026-03-05T21:54:08.241848Z#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
1772747648.242 sable_ircd     #x1B[2m2026-03-05T21:54:08.241893Z#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), 3)#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"
1772747648.342 1 -> S: PING synchronize485.678402587
1772747648.342 sable_ircd     #x1B[2m2026-03-05T21:54:08.342251Z#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
1772747648.342 sable_ircd     #x1B[2m2026-03-05T21:54:08.342310Z#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), 3)#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 synchronize485.678402587"
1772747649.095 sable_ircd     #x1B[2m2026-03-05T21:54:09.095210Z#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), 3): ERROR :Client quit
1772747649.096 sable_ircd     #x1B[2m2026-03-05T21:54:09.095434Z#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), 3))
1772747649.096 sable_ircd     #x1B[2m2026-03-05T21:54:09.095486Z#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), 3)
1772747649.096 sable_ircd     #x1B[2m2026-03-05T21:54:09.095641Z#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(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515346792550406, 99 => 817515346616791043) my clock=EventClock(1 => 817515346792550406, 99 => 817515346616791043)
1772747649.096 S -> 1: ERROR :Client quit
1772747649.096 sable_ircd     #x1B[2m2026-03-05T21:54:09.095999Z#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), 3) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed
1772747649.096 sable_ircd     #x1B[2m2026-03-05T21:54:09.096091Z#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
1772747649.096 sable_ircd     #x1B[2m2026-03-05T21:54:09.096094Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747649.098 sable_ircd     #x1B[2m2026-03-05T21:54:09.098026Z#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(817515354015141896)), timestamp: 1772747649, clock: EventClock(1 => 817515354002558983, 99 => 817515346616791043), target: User(UserId(Snowflake(817515345962078210))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515354002558983, 99 => 817515346616791043) my clock=EventClock(1 => 817515354002558983, 99 => 817515346616791043)
1772747649.098 sable_ircd     #x1B[2m2026-03-05T21:54:09.098302Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354015141896)), timestamp: 1772747649, clock: EventClock(1 => 817515354002558983, 99 => 817515346616791043), target: User(UserId(Snowflake(817515345962078210))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747649.099 sable_ircd     #x1B[2m2026-03-05T21:54:09.098453Z#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(817515354002558983)), timestamp: 1772747649, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(817515345962078211)), user: UserId(Snowflake(817515345962078210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747647 } }), users_to_notify: [] })
1772747649.099 sable_ircd     #x1B[2m2026-03-05T21:54:09.098509Z#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(817515354002558983)), timestamp: 1772747649, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(817515345962078211)), user: UserId(Snowflake(817515345962078210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747647 } }), users_to_notify: [] }
1772747649.099 sable_ircd     #x1B[2m2026-03-05T21:54:09.098606Z#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(817515354002558983)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] })
1772747649.099 sable_ircd     #x1B[2m2026-03-05T21:54:09.098679Z#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(817515354002558983)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] }
1772747649.102 sable_ircd     #x1B[2m2026-03-05T21:54:09.102092Z#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(817515354015141896)), timestamp: 1772747649, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] })
1772747649.102 sable_ircd     #x1B[2m2026-03-05T21:54:09.102144Z#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(817515354015141896)), timestamp: 1772747649, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515345962078210)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] }
1772747649.102 sable_ircd     #x1B[2m2026-03-05T21:54:09.102216Z#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(817515354015141896)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] })
1772747649.102 sable_ircd     #x1B[2m2026-03-05T21:54:09.102260Z#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(817515354015141896)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] }
1772747649.103 sable_services #x1B[2m2026-03-05T21:54:09.102863Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354015141896)), timestamp: 1772747649, clock: EventClock(1 => 817515354002558983, 99 => 817515346616791043), target: User(UserId(Snowflake(817515345962078210))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(817515354015141896)), timestamp: 1772747649, clock: EventClock(1 => 817515354002558983, 99 => 817515346616791043), target: User(UserId(Snowflake(817515345962078210))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515354002558983, 99 => 817515346616791043) my clock=EventClock(1 => 817515346792550406, 99 => 817515346616791043)
1772747649.103 sable_services #x1B[2m2026-03-05T21:54:09.102922Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354015141896)), timestamp: 1772747649, clock: EventClock(1 => 817515354002558983, 99 => 817515346616791043), target: User(UserId(Snowflake(817515345962078210))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(817515354002558983))]
1772747649.103 sable_services #x1B[2m2026-03-05T21:54:09.103244Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354015141896)), timestamp: 1772747649, clock: EventClock(1 => 817515354002558983, 99 => 817515346616791043), target: User(UserId(Snowflake(817515345962078210))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354015141896)), timestamp: 1772747649, clock: EventClock(1 => 817515354002558983, 99 => 817515346616791043), target: User(UserId(Snowflake(817515345962078210))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747649.139 sable_services #x1B[2m2026-03-05T21:54:09.138828Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515346792550406, 99 => 817515346616791043) my clock=EventClock(1 => 817515346792550406, 99 => 817515346616791043)
1772747649.139 sable_services #x1B[2m2026-03-05T21:54:09.138899Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(817515354015141896)), timestamp: 1772747649, clock: EventClock(1 => 817515354002558983, 99 => 817515346616791043), target: User(UserId(Snowflake(817515345962078210))), details: UserQuit(UserQuit { message: "Client Quit" }) }
1772747649.139 sable_services #x1B[2m2026-03-05T21:54:09.139206Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747649.144 sable_ircd     #x1B[2m2026-03-05T21:54:09.143539Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: GetEvent([EventId(Snowflake(817515354002558983))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(817515354002558983))]
1772747649.144 sable_ircd     #x1B[2m2026-03-05T21:54:09.143589Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747646), content: GetEvent([EventId(Snowflake(817515354002558983))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }]
1772747649.185 sable_services #x1B[2m2026-03-05T21:54:09.184627Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: BulkEvents([Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(817515354002558983)), timestamp: 1772747649, clock: EventClock(1 => 817515346792550406, 99 => 817515346616791043), target: UserConnection(UserConnectionId(Snowflake(817515345962078211))), details: UserDisconnect(UserDisconnect) }]
1772747649.196 1 -> S: PING synchronize486.532644845
1772747649.196 1: connects to server.
1772747649.197 1 -> S: CAP LS 302
1772747649.197 sable_ircd     #x1B[2m2026-03-05T21:54:09.196846Z#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), 4), remote_addr: 127.0.0.1, tls_info: None }
1772747649.197 sable_ircd     #x1B[2m2026-03-05T21:54:09.196990Z#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
1772747649.197 sable_ircd     #x1B[2m2026-03-05T21:54:09.197053Z#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), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection
1772747649.197 sable_ircd     #x1B[2m2026-03-05T21:54:09.197129Z#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), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server NOTICE * :*** Looking up your hostname
1772747649.197 sable_ircd     #x1B[2m2026-03-05T21:54:09.197330Z#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
1772747649.197 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747649.198 sable_ircd     #x1B[2m2026-03-05T21:54:09.197411Z#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), 4)#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"
1772747649.198 sable_ircd     #x1B[2m2026-03-05T21:54:09.197527Z#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), 4): :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL 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
1772747649.198 S -> 1: :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL 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
1772747649.198 1 -> S: CAP REQ :sasl
1772747649.198 sable_ircd     #x1B[2m2026-03-05T21:54:09.198177Z#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
1772747649.198 sable_ircd     #x1B[2m2026-03-05T21:54:09.198210Z#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), 4): 127.0.0.1/Some(Hostname("localhost"))
1772747649.198 sable_ircd     #x1B[2m2026-03-05T21:54:09.198236Z#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), 4): :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747649.198 sable_ircd     #x1B[2m2026-03-05T21:54:09.198260Z#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: 486, tv_nsec: 533770488 }, 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
1772747649.199 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747649.199 sable_ircd     #x1B[2m2026-03-05T21:54:09.198499Z#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
1772747649.199 sable_ircd     #x1B[2m2026-03-05T21:54:09.198558Z#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), 4)#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 :sasl"
1772747649.199 sable_ircd     #x1B[2m2026-03-05T21:54:09.198681Z#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), 4): :My.Little.Server CAP * ACK :sasl
1772747649.199 sable_ircd     #x1B[2m2026-03-05T21:54:09.198725Z#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), 4), ClientCapabilitySet(8))
1772747649.239 S -> 1: :My.Little.Server CAP * ACK :sasl
1772747649.240 1 -> S: NICK foo
1772747649.240 1 -> S: USER username * * :Realname
1772747649.240 1 -> S: CAP END
1772747649.240 sable_ircd     #x1B[2m2026-03-05T21:54:09.240075Z#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
1772747649.240 sable_ircd     #x1B[2m2026-03-05T21:54:09.240156Z#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), 4)#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"
1772747649.240 sable_ircd     #x1B[2m2026-03-05T21:54:09.240412Z#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: 486, tv_nsec: 533770488 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("foo")), 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
1772747649.241 sable_ircd     #x1B[2m2026-03-05T21:54:09.240495Z#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
1772747649.241 sable_ircd     #x1B[2m2026-03-05T21:54:09.240570Z#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
1772747649.241 sable_ircd     #x1B[2m2026-03-05T21:54:09.240623Z#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), 4)#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"
1772747649.241 sable_ircd     #x1B[2m2026-03-05T21:54:09.240733Z#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: 486, tv_nsec: 533770488 }, attach_user_id: OnceLock(<uninit>), 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(<uninit>), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747649.241 sable_ircd     #x1B[2m2026-03-05T21:54:09.240824Z#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
1772747649.241 sable_ircd     #x1B[2m2026-03-05T21:54:09.240876Z#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), 4)#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"
1772747650.241 1: waiting…
1772747650.246 sable_ircd     #x1B[2m2026-03-05T21:54:10.246268Z#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: 486, tv_nsec: 533770488 }, attach_user_id: OnceLock(<uninit>), 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(<uninit>), progress_flags: 0 } #x1B[3mflag#x1B[0m#x1B[2m=#x1B[0mCapNegotiation #x1B[3mresult#x1B[0m#x1B[2m=#x1B[0mtrue
1772747650.246 sable_ircd     #x1B[2m2026-03-05T21:54:10.246433Z#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), 4))
1772747650.247 sable_ircd     #x1B[2m2026-03-05T21:54:10.246682Z#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(817515358830202889)), timestamp: 1772747650, clock: EventClock(1 => 817515354015141896, 99 => 817515346616791043), target: User(UserId(Snowflake(817515358830202884))), 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(817515358830202885)), NewUserConnection { user: UserId(Snowflake(817515358830202884)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747650 })) }) }; event clock=EventClock(1 => 817515354015141896, 99 => 817515346616791043) my clock=EventClock(1 => 817515354015141896, 99 => 817515346616791043)
1772747650.247 sable_ircd     #x1B[2m2026-03-05T21:54:10.247049Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515358830202889)), timestamp: 1772747650, clock: EventClock(1 => 817515354015141896, 99 => 817515346616791043), target: User(UserId(Snowflake(817515358830202884))), 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(817515358830202885)), NewUserConnection { user: UserId(Snowflake(817515358830202884)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747650 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:44017 (127.0.0.1:44017)
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.248897Z#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(817515358830202889)), timestamp: 1772747650, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515358830202884)), 1) }), users_to_notify: [] })
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.248942Z#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(817515358830202889)), timestamp: 1772747650, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515358830202884)), 1) }), users_to_notify: [] }
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249012Z#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(817515358830202889)), timestamp: 1772747650, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515358830202884)), 2), connection: UserConnectionId(Snowflake(817515358830202885)) }), users_to_notify: [] })
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249056Z#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(817515358830202889)), timestamp: 1772747650, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515358830202884)), 2), connection: UserConnectionId(Snowflake(817515358830202885)) }), users_to_notify: [] }
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249126Z#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), 4): :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249175Z#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), 4): :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249220Z#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), 4): :My.Little.Server 003 foo :This server was created 2026-03-05 21:54:10.249206595 UTC
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249260Z#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), 4): :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249286Z#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), 4): :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
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249305Z#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), 4): :My.Little.Server 422 foo :MOTD File is missing
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249321Z#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), 4): :foo!username@localhost MODE foo +
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249352Z#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), 4): :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249386Z#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(817515358830202889)), timestamp: 1772747650, change: EventComplete(EventComplete), users_to_notify: [] })
1772747650.249 sable_ircd     #x1B[2m2026-03-05T21:54:10.249407Z#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(817515358830202889)), timestamp: 1772747650, change: EventComplete(EventComplete), users_to_notify: [] }
1772747650.250 S -> 1: :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo
1772747650.250 1 -> S: PING foo
1772747650.250 sable_ircd     #x1B[2m2026-03-05T21:54:10.250036Z#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
1772747650.250 sable_ircd     #x1B[2m2026-03-05T21:54:10.250078Z#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), 4)#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"
1772747650.290 sable_services #x1B[2m2026-03-05T21:54:10.289729Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515358830202889)), timestamp: 1772747650, clock: EventClock(1 => 817515354015141896, 99 => 817515346616791043), target: User(UserId(Snowflake(817515358830202884))), 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(817515358830202885)), NewUserConnection { user: UserId(Snowflake(817515358830202884)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747650 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(817515358830202889)), timestamp: 1772747650, clock: EventClock(1 => 817515354015141896, 99 => 817515346616791043), target: User(UserId(Snowflake(817515358830202884))), 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(817515358830202885)), NewUserConnection { user: UserId(Snowflake(817515358830202884)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747650 })) }) }; event clock=EventClock(1 => 817515354015141896, 99 => 817515346616791043) my clock=EventClock(1 => 817515354015141896, 99 => 817515346616791043)
1772747650.290 sable_services #x1B[2m2026-03-05T21:54:10.290086Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515358830202889)), timestamp: 1772747650, clock: EventClock(1 => 817515354015141896, 99 => 817515346616791043), target: User(UserId(Snowflake(817515358830202884))), 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(817515358830202885)), NewUserConnection { user: UserId(Snowflake(817515358830202884)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747650 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1772747646), content: NewEvent(Event { id: EventId(Snowflake(817515358830202889)), timestamp: 1772747650, clock: EventClock(1 => 817515354015141896, 99 => 817515346616791043), target: User(UserId(Snowflake(817515358830202884))), 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(817515358830202885)), NewUserConnection { user: UserId(Snowflake(817515358830202884)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747650 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:43089 (127.0.0.1:43089)
1772747650.350 1 -> S: PING synchronize487.686559924
1772747650.350 S -> 1: :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747650.350 S -> 1: :My.Little.Server 003 foo :This server was created 2026-03-05 21:54:10.249206595 UTC
1772747650.350 S -> 1: :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747650.350 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
1772747650.350 S -> 1: :My.Little.Server 422 foo :MOTD File is missing
1772747650.350 S -> 1: :foo!username@localhost MODE foo +
1772747650.350 S -> 1: :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747650.350 sable_ircd     #x1B[2m2026-03-05T21:54:10.350305Z#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
1772747650.350 sable_ircd     #x1B[2m2026-03-05T21:54:10.350380Z#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), 4)#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 synchronize487.686559924"
1772747651.103 sable_ircd     #x1B[2m2026-03-05T21:54:11.103167Z#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), 4): :My.Little.Server PONG My.Little.Server :foo
1772747651.104 S -> 1: :My.Little.Server PONG My.Little.Server :foo
1772747652.105 1: waiting…
1772747652.108 sable_ircd     #x1B[2m2026-03-05T21:54:12.107667Z#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), 4): :My.Little.Server PONG My.Little.Server :synchronize487.686559924
1772747652.108 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize487.686559924
1772747652.809 1 -> S: AUTHENTICATE PLAIN
1772747652.810 sable_ircd     #x1B[2m2026-03-05T21:54:12.809591Z#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
1772747652.810 sable_ircd     #x1B[2m2026-03-05T21:54:12.809659Z#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), 4)#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"AUTHENTICATE PLAIN"
1772747653.061 sable_ircd     #x1B[2m2026-03-05T21:54:13.061388Z#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), 4): :My.Little.Server 462 foo :You are already connected and cannot handshake again
1772747653.062 sable_ircd     #x1B[2m2026-03-05T21:54:13.061464Z#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
1772747654.909 1 -> S: PING synchronize492.246014391
1772747654.909 S -> 1: :My.Little.Server 462 foo :You are already connected and cannot handshake again
1772747654.910 sable_ircd     #x1B[2m2026-03-05T21:54:14.909944Z#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
1772747654.910 sable_ircd     #x1B[2m2026-03-05T21:54:14.910022Z#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), 4)#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 synchronize492.246014391"
1772747654.910 sable_ircd     #x1B[2m2026-03-05T21:54:14.910164Z#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), 4): :My.Little.Server PONG My.Little.Server :synchronize492.246014391
1772747654.910 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize492.246014391
1772747654.913 sable_ircd     #x1B[2m2026-03-05T21:54:14.912933Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1mhandle_connection#x1B[0m#x1B[1m{#x1B[0m#x1B[3mconn#x1B[0m#x1B[2m=#x1B[0mPollEvented { io: Some(TcpStream { addr: 127.0.0.1:43089, peer: 127.0.0.1:52545, fd: 19 }) } #x1B[3mmessage_sender#x1B[0m#x1B[2m=#x1B[0mUnboundedSender { chan: Tx { inner: Chan { tx: Tx { block_tail: 0x5607d1efa090, tail_position: 0 }, semaphore: Semaphore(0), rx_waker: AtomicWaker, tx_count: 2, rx_fields: "..." } } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error handling message: I/O error: unexpected end of file
1772747654.913 sable_ircd     #x1B[2m2026-03-05T21:54:14.913058Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error in outbound network sync connection: I/O error: unexpected end of file
1772747654.918 1: disconnects from server.