--------------------------------- Captured Out ---------------------------------
---- new test ----
1788127942.061 sable_ircd     uid=1001
1788127942.063 sable_ircd     #x1B[2m2026-08-30T22:12:22.063185Z#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(882024978319413248)), timestamp: 1788127942, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1788127942, name: ServerName("My.Little.Server"), ts: 1788127942, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1788127942.063 sable_ircd     #x1B[2m2026-08-30T22:12:22.063280Z#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
1788127942.064 sable_ircd     #x1B[2m2026-08-30T22:12:22.063443Z#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(882024978319413248)), timestamp: 1788127942, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1788127942, name: ServerName("My.Little.Server"), ts: 1788127942, 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
1788127942.064 sable_ircd     #x1B[2m2026-08-30T22:12:22.063584Z#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(882024978319413248)), timestamp: 1788127942, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1788127942.064 sable_ircd     #x1B[2m2026-08-30T22:12:22.063613Z#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(882024978319413248)), timestamp: 1788127942, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1788127942.064 sable_ircd     #x1B[2m2026-08-30T22:12:22.063645Z#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(882024978319413248)), timestamp: 1788127942, change: EventComplete(EventComplete), users_to_notify: [] })
1788127942.064 sable_ircd     #x1B[2m2026-08-30T22:12:22.063661Z#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(882024978319413248)), timestamp: 1788127942, change: EventComplete(EventComplete), users_to_notify: [] }
1788127942.065 sable_ircd     #x1B[2m2026-08-30T22:12:22.064599Z#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
1788127942.065 sable_ircd     #x1B[2m2026-08-30T22:12:22.064724Z#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(882024978323607553)), timestamp: 1788127942, clock: EventClock(1 => 882024978319413248), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1788127942 }) }; event clock=EventClock(1 => 882024978319413248) my clock=EventClock(1 => 882024978319413248)
1788127942.065 sable_ircd     #x1B[2m2026-08-30T22:12:22.064774Z#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
1788127942.065 sable_ircd     #x1B[2m2026-08-30T22:12:22.064913Z#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(882024978323607553)), timestamp: 1788127942, change: EventComplete(EventComplete), users_to_notify: [] })
1788127942.065 sable_ircd     #x1B[2m2026-08-30T22:12:22.064936Z#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(882024978323607553)), timestamp: 1788127942, change: EventComplete(EventComplete), users_to_notify: [] }
1788127942.157 sable_ircd     #x1B[2m2026-08-30T22:12:22.157188Z#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 }
1788127942.157 sable_ircd     #x1B[2m2026-08-30T22:12:22.157303Z#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
1788127942.157 sable_ircd     #x1B[2m2026-08-30T22:12:22.157336Z#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
1788127942.157 sable_ircd     #x1B[2m2026-08-30T22:12:22.157422Z#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
1788127942.158 sable_ircd     #x1B[2m2026-08-30T22:12:22.157557Z#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
1788127942.158 sable_ircd     #x1B[2m2026-08-30T22:12:22.157603Z#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"
1788127942.158 sable_ircd     #x1B[2m2026-08-30T22:12:22.157704Z#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
1788127942.158 sable_ircd     #x1B[2m2026-08-30T22:12:22.157783Z#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))
1788127942.158 sable_ircd     #x1B[2m2026-08-30T22:12:22.157811Z#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)
1788127942.158 sable_ircd     #x1B[2m2026-08-30T22:12:22.158120Z#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
1788127942.158 sable_ircd     #x1B[2m2026-08-30T22:12:22.158187Z#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
1788127942.158 sable_ircd     #x1B[2m2026-08-30T22:12:22.158331Z#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
1788127942.178 1: connects to server.
1788127942.178 1 -> S: NICK foo
1788127942.178 1 -> S: USER username * * :Realname
1788127942.178 sable_ircd     #x1B[2m2026-08-30T22:12:22.178221Z#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 }
1788127942.178 sable_ircd     #x1B[2m2026-08-30T22:12:22.178309Z#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
1788127942.178 sable_ircd     #x1B[2m2026-08-30T22:12:22.178342Z#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
1788127942.178 sable_ircd     #x1B[2m2026-08-30T22:12:22.178403Z#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
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.178520Z#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
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.178557Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK foo"
1788127942.179 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.178682Z#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: 588, tv_nsec: 299990868 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("foo")), 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
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.178735Z#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
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.178754Z#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
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.178772Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER username * * :Realname"
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.178820Z#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: 588, tv_nsec: 299990868 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("foo")), realname: OnceLock(Realname("Realname")), 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
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.179195Z#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
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.179234Z#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"))
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.179267Z#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
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.179289Z#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: 588, tv_nsec: 299990868 }, 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[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1788127942.179 sable_ircd     #x1B[2m2026-08-30T22:12:22.179337Z#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))
1788127942.180 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.179558Z#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(882024978805952514)), timestamp: 1788127942, clock: EventClock(1 => 882024978323607553), target: User(UserId(Snowflake(882024978805952512))), 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(882024978805952513)), NewUserConnection { user: UserId(Snowflake(882024978805952512)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1788127942 })) }) }; event clock=EventClock(1 => 882024978323607553) my clock=EventClock(1 => 882024978323607553)
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.179638Z#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
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.179888Z#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(882024978805952514)), timestamp: 1788127942, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(882024978805952512)), 1) }), users_to_notify: [] })
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.179959Z#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(882024978805952514)), timestamp: 1788127942, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(882024978805952512)), 1) }), users_to_notify: [] }
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180229Z#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(882024978805952514)), timestamp: 1788127942, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(882024978805952512)), 2), connection: UserConnectionId(Snowflake(882024978805952513)) }), users_to_notify: [] })
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180261Z#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(882024978805952514)), timestamp: 1788127942, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(882024978805952512)), 2), connection: UserConnectionId(Snowflake(882024978805952513)) }), users_to_notify: [] }
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180312Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180340Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180365Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 003 foo :This server was created 2026-08-30 22:12:22.180355525 UTC
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180402Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180438Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 005 foo EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180462Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 422 foo :MOTD File is missing
1788127942.180 sable_ircd     #x1B[2m2026-08-30T22:12:22.180484Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :foo!username@localhost MODE foo +
1788127942.181 sable_ircd     #x1B[2m2026-08-30T22:12:22.180501Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1788127942.181 sable_ircd     #x1B[2m2026-08-30T22:12:22.180531Z#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(882024978805952514)), timestamp: 1788127942, change: EventComplete(EventComplete), users_to_notify: [] })
1788127942.181 sable_ircd     #x1B[2m2026-08-30T22:12:22.180544Z#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(882024978805952514)), timestamp: 1788127942, change: EventComplete(EventComplete), users_to_notify: [] }
1788127942.181 S -> 1: :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo
1788127942.181 S -> 1: :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1788127942.181 S -> 1: :My.Little.Server 003 foo :This server was created 2026-08-30 22:12:22.180355525 UTC
1788127942.181 S -> 1: :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1788127942.181 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
1788127942.181 S -> 1: :My.Little.Server 422 foo :MOTD File is missing
1788127942.181 S -> 1: :foo!username@localhost MODE foo +
1788127942.181 S -> 1: :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1788127942.181 1 -> S: PING foo
1788127942.181 sable_ircd     #x1B[2m2026-08-30T22:12:22.181043Z#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
1788127942.181 sable_ircd     #x1B[2m2026-08-30T22:12:22.181072Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING foo"
1788127942.181 sable_ircd     #x1B[2m2026-08-30T22:12:22.181123Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server PONG My.Little.Server :foo
1788127942.313 sable_history  #x1B[2m2027-08-30T23:30:48.943213Z#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(1014316291345031255)), timestamp: 1819668648, clock: EventClock(1 => 1014295140782575617, 50 => 1014316039682596950), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819668648 }) }; event clock=EventClock(1 => 1014295140782575617, 50 => 1014316039682596950) my clock=EventClock(1 => 1014295140782575617, 50 => 1014316039682596950)
1788127942.313 sable_history  #x1B[2m2027-08-30T23:30:48.944742Z#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
1788127942.700 sable_history  #x1B[2m2027-08-30T23:39:14.293863Z#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(1014318410940751969)), timestamp: 1819669154, clock: EventClock(1 => 1014295494442094614, 50 => 1014318159282511968, 99 => 1014295497634361348), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819669154 }) }; event clock=EventClock(1 => 1014295494442094614, 50 => 1014318159282511968, 99 => 1014295497634361348) my clock=EventClock(1 => 1014295494442094614, 50 => 1014318159282511968, 99 => 1014295497634361348)
1788127942.700 sable_history  #x1B[2m2027-08-30T23:39:14.295416Z#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
1788127942.746 sable_history  #x1B[2m2027-08-30T23:30:49.167633Z#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(1014316292288749655)), timestamp: 1819668649, clock: EventClock(1 => 1014295142766481409, 50 => 1014316040630509654), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819668649 }) }; event clock=EventClock(1 => 1014295142766481409, 50 => 1014316040630509654) my clock=EventClock(1 => 1014295142766481409, 50 => 1014316040630509654)
1788127942.746 sable_history  #x1B[2m2027-08-30T23:30:49.169298Z#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
1788127942.982 1 -> S: PING synchronize589.103415799
1788127942.982 S -> 1: :My.Little.Server PONG My.Little.Server :foo
1788127942.982 sable_ircd     #x1B[2m2026-08-30T22:12:22.982396Z#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
1788127942.982 sable_ircd     #x1B[2m2026-08-30T22:12:22.982466Z#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 synchronize589.103415799"
1788127942.983 sable_ircd     #x1B[2m2026-08-30T22:12:22.982608Z#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 :synchronize589.103415799
1788127942.983 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize589.103415799
1788127942.983 2: connects to server.
1788127942.983 2 -> S: NICK bar
1788127942.983 2 -> S: USER username * * :Realname
1788127942.983 sable_ircd     #x1B[2m2026-08-30T22:12:22.983232Z#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 }
1788127942.983 sable_ircd     #x1B[2m2026-08-30T22:12:22.983304Z#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
1788127942.983 sable_ircd     #x1B[2m2026-08-30T22:12:22.983329Z#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
1788127942.983 sable_ircd     #x1B[2m2026-08-30T22:12:22.983374Z#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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.983496Z#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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.983529Z#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 bar"
1788127942.984 S -> 2: :My.Little.Server NOTICE * :*** Looking up your hostname
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.983646Z#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: 589, tv_nsec: 104980531 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("bar")), 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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.983695Z#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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.983727Z#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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.983754Z#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 username * * :Realname"
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.983814Z#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: 589, tv_nsec: 104980531 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("bar")), realname: OnceLock(Realname("Realname")), 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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.984322Z#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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.984345Z#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"))
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.984364Z#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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.984398Z#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: 589, tv_nsec: 104980531 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("bar")), realname: OnceLock(Realname("Realname")), 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
1788127942.984 sable_ircd     #x1B[2m2026-08-30T22:12:22.984429Z#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))
1788127942.985 S -> 2: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.984621Z#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(882024982182367235)), timestamp: 1788127942, clock: EventClock(1 => 882024978805952514), target: User(UserId(Snowflake(882024982182367234))), details: NewUser(NewUser { nickname: Nickname("bar"), 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(882024982182367235)), NewUserConnection { user: UserId(Snowflake(882024982182367234)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1788127942 })) }) }; event clock=EventClock(1 => 882024978805952514) my clock=EventClock(1 => 882024978805952514)
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.984721Z#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
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.984946Z#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(882024982182367235)), timestamp: 1788127942, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(882024982182367234)), 1) }), users_to_notify: [] })
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.984977Z#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(882024982182367235)), timestamp: 1788127942, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(882024982182367234)), 1) }), users_to_notify: [] }
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985014Z#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(882024982182367235)), timestamp: 1788127942, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(882024982182367234)), 2), connection: UserConnectionId(Snowflake(882024982182367235)) }), users_to_notify: [] })
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985031Z#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(882024982182367235)), timestamp: 1788127942, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(882024982182367234)), 2), connection: UserConnectionId(Snowflake(882024982182367235)) }), users_to_notify: [] }
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985082Z#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 bar :Welcome to the test Internet Relay Chat network, bar
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985104Z#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 bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985129Z#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 bar :This server was created 2026-08-30 22:12:22.985119906 UTC
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985147Z#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 bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985176Z#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 bar 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
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985193Z#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 bar :MOTD File is missing
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985213Z#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): :bar!username@localhost MODE bar +
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985230Z#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 bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985258Z#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(882024982182367235)), timestamp: 1788127942, change: EventComplete(EventComplete), users_to_notify: [] })
1788127942.985 sable_ircd     #x1B[2m2026-08-30T22:12:22.985275Z#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(882024982182367235)), timestamp: 1788127942, change: EventComplete(EventComplete), users_to_notify: [] }
1788127942.985 S -> 2: :My.Little.Server 001 bar :Welcome to the test Internet Relay Chat network, bar
1788127942.986 2 -> S: PING foo
1788127942.986 sable_ircd     #x1B[2m2026-08-30T22:12:22.985697Z#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
1788127942.986 sable_ircd     #x1B[2m2026-08-30T22:12:22.985725Z#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 foo"
1788127942.986 sable_ircd     #x1B[2m2026-08-30T22:12:22.985774Z#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 :foo
1788127943.086 2 -> S: PING synchronize589.207262967
1788127943.086 S -> 2: :My.Little.Server 002 bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1788127943.086 S -> 2: :My.Little.Server 003 bar :This server was created 2026-08-30 22:12:22.985119906 UTC
1788127943.086 S -> 2: :My.Little.Server 004 bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1788127943.086 S -> 2: :My.Little.Server 005 bar 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
1788127943.086 S -> 2: :My.Little.Server 422 bar :MOTD File is missing
1788127943.086 S -> 2: :bar!username@localhost MODE bar +
1788127943.086 S -> 2: :My.Little.Server NOTICE bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1788127943.086 S -> 2: :My.Little.Server PONG My.Little.Server :foo
1788127943.086 sable_ircd     #x1B[2m2026-08-30T22:12:23.086155Z#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
1788127943.086 sable_ircd     #x1B[2m2026-08-30T22:12:23.086211Z#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 synchronize589.207262967"
1788127943.086 sable_ircd     #x1B[2m2026-08-30T22:12:23.086324Z#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 :synchronize589.207262967
1788127943.087 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize589.207262967
1788127943.128 sable_history  #x1B[2m2027-08-30T23:30:49.548048Z#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(1014316293882585175)), timestamp: 1819668649, clock: EventClock(1 => 1014295144268042241, 50 => 1014316042224345174), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819668649 }) }; event clock=EventClock(1 => 1014295144268042241, 50 => 1014316042224345174) my clock=EventClock(1 => 1014295144268042241, 50 => 1014316042224345174)
1788127943.128 sable_history  #x1B[2m2027-08-30T23:30:49.549538Z#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
1788127943.632 sable_history  #x1B[2m2027-08-30T23:42:02.100817Z#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(1014319114770128996)), timestamp: 1819669322, clock: EventClock(1 => 1014295448346693654, 50 => 1014318863111888995, 99 => 1014295451547348996), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819669322 }) }; event clock=EventClock(1 => 1014295448346693654, 50 => 1014318863111888995, 99 => 1014295451547348996) my clock=EventClock(1 => 1014295448346693654, 50 => 1014318863111888995, 99 => 1014295451547348996)
1788127943.632 sable_history  #x1B[2m2027-08-30T23:42:02.102318Z#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
1788127943.649 sable_history  #x1B[2m2027-08-30T23:30:50.148048Z#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(1014316296399167576)), timestamp: 1819668650, clock: EventClock(1 => 1014295224354082831, 50 => 1014316044740927575, 99 => 1014295214413983748), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1819668650 }) }; event clock=EventClock(1 => 1014295224354082831, 50 => 1014316044740927575, 99 => 1014295214413983748) my clock=EventClock(1 => 1014295224354082831, 50 => 1014316044740927575, 99 => 1014295214413983748)
1788127943.649 sable_history  #x1B[2m2027-08-30T23:30:50.149054Z#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
1788127943.787 1 -> S: PRIVMSG bar hi
1788127943.788 sable_ircd     #x1B[2m2026-08-30T22:12:23.787928Z#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
1788127943.788 sable_ircd     #x1B[2m2026-08-30T22:12:23.787991Z#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"PRIVMSG bar hi"
1788127943.788 sable_ircd     #x1B[2m2026-08-30T22:12:23.788335Z#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(882024985554587652)), timestamp: 1788127943, clock: EventClock(1 => 882024982182367235), target: Message(MessageId(Uuid7(01a054bb-1c6c-7d21-be17-5c9bafce0ea4))), details: NewMessage(NewMessage { source: UserId(Snowflake(882024978805952512)), target: User(UserId(Snowflake(882024982182367234))), message_type: Privmsg, text: "hi" }) }; event clock=EventClock(1 => 882024982182367235) my clock=EventClock(1 => 882024982182367235)
1788127943.789 sable_ircd     #x1B[2m2026-08-30T22:12:23.788487Z#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
1788127943.789 sable_ircd     #x1B[2m2026-08-30T22:12:23.788486Z#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
1788127943.789 sable_ircd     #x1B[2m2026-08-30T22:12:23.788746Z#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(882024985554587652)), timestamp: 1788127943, change: NewMessage(NewMessage { message: MessageId(Uuid7(01a054bb-1c6c-7d21-be17-5c9bafce0ea4)), source: User(HistoricUserId(UserId(Snowflake(882024978805952512)), 2)), target: User(HistoricUserId(UserId(Snowflake(882024982182367234)), 2)) }), users_to_notify: [UserId(Snowflake(882024982182367234)), UserId(Snowflake(882024978805952512))] })
1788127943.789 sable_ircd     #x1B[2m2026-08-30T22:12:23.788778Z#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(882024985554587652)), timestamp: 1788127943, change: NewMessage(NewMessage { message: MessageId(Uuid7(01a054bb-1c6c-7d21-be17-5c9bafce0ea4)), source: User(HistoricUserId(UserId(Snowflake(882024978805952512)), 2)), target: User(HistoricUserId(UserId(Snowflake(882024982182367234)), 2)) }), users_to_notify: [UserId(Snowflake(882024982182367234)), UserId(Snowflake(882024978805952512))] }
1788127943.789 sable_ircd     #x1B[2m2026-08-30T22:12:23.788834Z#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): :foo!username@localhost PRIVMSG bar :hi
1788127943.789 sable_ircd     #x1B[2m2026-08-30T22:12:23.788878Z#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(882024985554587652)), timestamp: 1788127943, change: EventComplete(EventComplete), users_to_notify: [] })
1788127943.789 sable_ircd     #x1B[2m2026-08-30T22:12:23.788893Z#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(882024985554587652)), timestamp: 1788127943, change: EventComplete(EventComplete), users_to_notify: [] }
1788127943.888 1 -> S: PING synchronize590.009251348
1788127943.888 sable_ircd     #x1B[2m2026-08-30T22:12:23.888117Z#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
1788127943.888 sable_ircd     #x1B[2m2026-08-30T22:12:23.888173Z#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 synchronize590.009251348"
1788127944.140 sable_ircd     #x1B[2m2026-08-30T22:12:24.139769Z#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 :synchronize590.009251348
1788127944.140 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize590.009251348
1788127944.240 2 -> S: PING synchronize590.361878237
1788127944.240 S -> 2: :foo!username@localhost PRIVMSG bar :hi
1788127944.241 sable_ircd     #x1B[2m2026-08-30T22:12:24.240722Z#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
1788127944.241 sable_ircd     #x1B[2m2026-08-30T22:12:24.240770Z#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 synchronize590.361878237"
1788127944.241 sable_ircd     #x1B[2m2026-08-30T22:12:24.240866Z#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 :synchronize590.361878237
1788127944.241 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize590.361878237
1788127944.241 (escaped) 1 -> S: b'PRIVMSG bar hi\xaa'
1788127944.241 sable_ircd     #x1B[2m2026-08-30T22:12:24.241297Z#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[0mIoError("stream did not contain valid UTF-8")
1788127944.241 sable_ircd     #x1B[2m2026-08-30T22:12:24.241357Z#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
1788127944.241 sable_ircd     #x1B[2m2026-08-30T22:12:24.241427Z#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): ERROR :I/O Error: stream did not contain valid UTF-8
1788127944.241 sable_ircd     #x1B[2m2026-08-30T22:12:24.241464Z#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::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 2)
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241549Z#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(882024987454607365)), timestamp: 1788127944, clock: EventClock(1 => 882024985554587652), target: UserConnection(UserConnectionId(Snowflake(882024978805952513))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 882024985554587652) my clock=EventClock(1 => 882024985554587652)
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241627Z#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
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241672Z#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(882024987454607366)), timestamp: 1788127944, clock: EventClock(1 => 882024987454607365), target: User(UserId(Snowflake(882024978805952512))), details: UserQuit(UserQuit { message: "I/O Error: stream did not contain valid UTF-8" }) }; event clock=EventClock(1 => 882024987454607365) my clock=EventClock(1 => 882024987454607365)
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241690Z#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
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241801Z#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
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241847Z#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(882024987454607365)), timestamp: 1788127944, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(882024978805952512)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(882024978805952513)), user: UserId(Snowflake(882024978805952512)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1788127942 } }), users_to_notify: [] })
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241876Z#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(882024987454607365)), timestamp: 1788127944, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(882024978805952512)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(882024978805952513)), user: UserId(Snowflake(882024978805952512)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1788127942 } }), users_to_notify: [] }
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241920Z#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(882024987454607365)), timestamp: 1788127944, change: EventComplete(EventComplete), users_to_notify: [] })
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241939Z#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(882024987454607365)), timestamp: 1788127944, change: EventComplete(EventComplete), users_to_notify: [] }
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241966Z#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(882024987454607366)), timestamp: 1788127944, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(882024978805952512)), 2), nickname: Nickname("foo"), message: "I/O Error: stream did not contain valid UTF-8", memberships: [] }), users_to_notify: [] })
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.241985Z#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(882024987454607366)), timestamp: 1788127944, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(882024978805952512)), 2), nickname: Nickname("foo"), message: "I/O Error: stream did not contain valid UTF-8", memberships: [] }), users_to_notify: [] }
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.242012Z#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(882024987454607366)), timestamp: 1788127944, change: EventComplete(EventComplete), users_to_notify: [] })
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.242034Z#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(882024987454607366)), timestamp: 1788127944, change: EventComplete(EventComplete), users_to_notify: [] }
1788127944.242 sable_ircd     #x1B[2m2026-08-30T22:12:24.242057Z#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
1788127944.341 1 -> S: PING synchronize590.462897348
1788127944.341 S -> 1: ERROR :I/O Error: stream did not contain valid UTF-8
1788127944.344 1: disconnects from server.
1788127944.344 2: disconnects from server.