--------------------------------- Captured Out ---------------------------------
---- new test ----
1782833839.086 sable_ircd     uid=1001
1782833839.090 sable_ircd     #x1B[2m2026-06-30T15:37:19.089501Z#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(859819901039153152)), timestamp: 1782833839, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1782833839, name: ServerName("My.Little.Server"), ts: 1782833839, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1782833839.090 sable_ircd     #x1B[2m2026-06-30T15:37:19.089636Z#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
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.089895Z#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(859819901039153152)), timestamp: 1782833839, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1782833839, name: ServerName("My.Little.Server"), ts: 1782833839, 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
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.090119Z#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(859819901043347457)), timestamp: 1782833839, clock: EventClock(1 => 859819901039153152), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1782833839 }) }; event clock=EventClock(1 => 859819901039153152) my clock=EventClock(1 => 859819901039153152)
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.090183Z#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
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.090558Z#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(859819901039153152)), timestamp: 1782833839, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.090602Z#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(859819901039153152)), timestamp: 1782833839, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.090644Z#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(859819901039153152)), timestamp: 1782833839, change: EventComplete(EventComplete), users_to_notify: [] })
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.090668Z#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(859819901039153152)), timestamp: 1782833839, change: EventComplete(EventComplete), users_to_notify: [] }
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.090716Z#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(859819901043347457)), timestamp: 1782833839, change: EventComplete(EventComplete), users_to_notify: [] })
1782833839.091 sable_ircd     #x1B[2m2026-06-30T15:37:19.090732Z#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(859819901043347457)), timestamp: 1782833839, change: EventComplete(EventComplete), users_to_notify: [] }
1782833839.092 sable_ircd     #x1B[2m2026-06-30T15:37:19.091725Z#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
1782833839.182 sable_ircd     #x1B[2m2026-06-30T15:37:19.181983Z#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 }
1782833839.182 sable_ircd     #x1B[2m2026-06-30T15:37:19.182113Z#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
1782833839.182 sable_ircd     #x1B[2m2026-06-30T15:37:19.182156Z#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
1782833839.182 sable_ircd     #x1B[2m2026-06-30T15:37:19.182214Z#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
1782833839.182 sable_ircd     #x1B[2m2026-06-30T15:37:19.182404Z#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
1782833839.183 sable_ircd     #x1B[2m2026-06-30T15:37:19.182477Z#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"
1782833839.183 sable_ircd     #x1B[2m2026-06-30T15:37:19.182610Z#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
1782833839.183 sable_ircd     #x1B[2m2026-06-30T15:37:19.182737Z#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))
1782833839.183 sable_ircd     #x1B[2m2026-06-30T15:37:19.182782Z#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)
1782833839.183 sable_ircd     #x1B[2m2026-06-30T15:37:19.183389Z#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
1782833839.184 sable_ircd     #x1B[2m2026-06-30T15:37:19.183539Z#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
1782833839.184 sable_ircd     #x1B[2m2026-06-30T15:37:19.183621Z#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
1782833839.203 1: connects to server.
1782833839.203 1 -> S: NICK foo
1782833839.203 1 -> S: USER username * * :Realname
1782833839.203 sable_ircd     #x1B[2m2026-06-30T15:37:19.203312Z#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 }
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.203510Z#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
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.203559Z#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
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.203608Z#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
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.203779Z#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
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.203852Z#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"
1782833839.204 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.204039Z#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: 623, tv_nsec: 427243687 }, 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
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.204135Z#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
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.204186Z#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
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.204235Z#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"
1782833839.204 sable_ircd     #x1B[2m2026-06-30T15:37:19.204331Z#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: 623, tv_nsec: 427243687 }, 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
1782833839.205 sable_ircd     #x1B[2m2026-06-30T15:37:19.204928Z#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
1782833839.205 sable_ircd     #x1B[2m2026-06-30T15:37:19.204969Z#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"))
1782833839.205 sable_ircd     #x1B[2m2026-06-30T15:37:19.204998Z#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
1782833839.205 sable_ircd     #x1B[2m2026-06-30T15:37:19.205020Z#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: 623, tv_nsec: 427243687 }, 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
1782833839.205 sable_ircd     #x1B[2m2026-06-30T15:37:19.205066Z#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))
1782833839.205 sable_ircd     #x1B[2m2026-06-30T15:37:19.205317Z#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(859819901525692418)), timestamp: 1782833839, clock: EventClock(1 => 859819901043347457), target: User(UserId(Snowflake(859819901525692416))), 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(859819901525692417)), NewUserConnection { user: UserId(Snowflake(859819901525692416)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1782833839 })) }) }; event clock=EventClock(1 => 859819901043347457) my clock=EventClock(1 => 859819901043347457)
1782833839.205 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.205473Z#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
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.205754Z#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(859819901525692418)), timestamp: 1782833839, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(859819901525692416)), 1) }), users_to_notify: [] })
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.205799Z#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(859819901525692418)), timestamp: 1782833839, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(859819901525692416)), 1) }), users_to_notify: [] }
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.205881Z#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(859819901525692418)), timestamp: 1782833839, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(859819901525692416)), 2), connection: UserConnectionId(Snowflake(859819901525692417)) }), users_to_notify: [] })
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.205923Z#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(859819901525692418)), timestamp: 1782833839, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(859819901525692416)), 2), connection: UserConnectionId(Snowflake(859819901525692417)) }), users_to_notify: [] }
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.205991Z#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
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206033Z#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
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206080Z#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-06-30 15:37:19.206063974 UTC
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206117Z#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
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206184Z#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
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206220Z#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
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206262Z#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 +
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206299Z#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.
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206393Z#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(859819901525692418)), timestamp: 1782833839, change: EventComplete(EventComplete), users_to_notify: [] })
1782833839.206 sable_ircd     #x1B[2m2026-06-30T15:37:19.206427Z#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(859819901525692418)), timestamp: 1782833839, change: EventComplete(EventComplete), users_to_notify: [] }
1782833839.207 S -> 1: :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo
1782833839.207 S -> 1: :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1782833839.207 S -> 1: :My.Little.Server 003 foo :This server was created 2026-06-30 15:37:19.206063974 UTC
1782833839.207 S -> 1: :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1782833839.207 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
1782833839.207 S -> 1: :My.Little.Server 422 foo :MOTD File is missing
1782833839.207 S -> 1: :foo!username@localhost MODE foo +
1782833839.207 S -> 1: :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1782833839.207 1 -> S: PING foo
1782833839.207 sable_ircd     #x1B[2m2026-06-30T15:37:19.207150Z#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
1782833839.207 sable_ircd     #x1B[2m2026-06-30T15:37:19.207202Z#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"
1782833839.207 sable_ircd     #x1B[2m2026-06-30T15:37:19.207310Z#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
1782833839.327 sable_history  #x1B[2m2027-06-30T16:46:26.204214Z#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(992108866240192590)), timestamp: 1814373986, clock: EventClock(1 => 992090986400190497, 50 => 992108614581952589, 99 => 992090992696242181), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1814373986 }) }; event clock=EventClock(1 => 992090986400190497, 50 => 992108614581952589, 99 => 992090992696242181) my clock=EventClock(1 => 992090986400190497, 50 => 992108614581952589, 99 => 992090992696242181)
1782833839.327 sable_history  #x1B[2m2027-06-30T16:46:26.205892Z#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
1782833839.553 sable_history  #x1B[2m2027-06-30T16:43:38.916905Z#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(992108164587659338)), timestamp: 1814373818, clock: EventClock(1 => 992090282621145089, 50 => 992107912925225033), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1814373818 }) }; event clock=EventClock(1 => 992090282621145089, 50 => 992107912925225033) my clock=EventClock(1 => 992090282621145089, 50 => 992107912925225033)
1782833839.553 sable_history  #x1B[2m2027-06-30T16:43:38.918609Z#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
1782833840.001 sable_history  #x1B[2m2027-06-30T16:43:38.827265Z#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(992108164210171978)), timestamp: 1814373818, clock: EventClock(1 => 992090284479221761, 50 => 992107912551931977), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1814373818 }) }; event clock=EventClock(1 => 992090284479221761, 50 => 992107912551931977) my clock=EventClock(1 => 992090284479221761, 50 => 992107912551931977)
1782833840.001 sable_history  #x1B[2m2027-06-30T16:43:38.829196Z#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
1782833840.008 1 -> S: PING synchronize624.231549876
1782833840.008 S -> 1: :My.Little.Server PONG My.Little.Server :foo
1782833840.008 sable_ircd     #x1B[2m2026-06-30T15:37:20.008321Z#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
1782833840.008 sable_ircd     #x1B[2m2026-06-30T15:37:20.008409Z#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 synchronize624.231549876"
1782833840.009 sable_ircd     #x1B[2m2026-06-30T15:37:20.008545Z#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 :synchronize624.231549876
1782833840.009 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize624.231549876
1782833840.009 1 -> S: PART #chan
1782833840.009 sable_ircd     #x1B[2m2026-06-30T15:37:20.009161Z#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
1782833840.009 sable_ircd     #x1B[2m2026-06-30T15:37:20.009201Z#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"PART #chan"
1782833840.009 sable_ircd     #x1B[2m2026-06-30T15:37:20.009310Z#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 403 foo #chan :No such channel
1782833840.009 sable_ircd     #x1B[2m2026-06-30T15:37:20.009379Z#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
1782833840.109 1 -> S: PING synchronize624.332709625
1782833840.109 S -> 1: :My.Little.Server 403 foo #chan :No such channel
1782833840.110 sable_ircd     #x1B[2m2026-06-30T15:37:20.109491Z#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
1782833840.110 sable_ircd     #x1B[2m2026-06-30T15:37:20.109549Z#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 synchronize624.332709625"
1782833840.394 sable_history  #x1B[2m2027-06-30T16:49:15.217264Z#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(992109575132094545)), timestamp: 1814374155, clock: EventClock(1 => 992090688797544470, 50 => 992109323478048848, 99 => 992090692702842884), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1814374155 }) }; event clock=EventClock(1 => 992090688797544470, 50 => 992109323478048848, 99 => 992090692702842884) my clock=EventClock(1 => 992090688797544470, 50 => 992109323478048848, 99 => 992090692702842884)
1782833840.395 sable_history  #x1B[2m2027-06-30T16:49:15.219436Z#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
1782833840.447 sable_history  #x1B[2m2027-06-30T16:43:39.242934Z#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(992108165955002442)), timestamp: 1814373819, clock: EventClock(1 => 992090293064962051, 50 => 992107914300956745), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1814373819 }) }; event clock=EventClock(1 => 992090293064962051, 50 => 992107914300956745) my clock=EventClock(1 => 992090293064962051, 50 => 992107914300956745)
1782833840.447 sable_history  #x1B[2m2027-06-30T16:43:39.244339Z#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
1782833840.901 sable_history  #x1B[2m2027-06-30T16:43:39.930939Z#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(992108168840683594)), timestamp: 1814373819, clock: EventClock(1 => 992090288103100417, 50 => 992107917186637897), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1814373819 }) }; event clock=EventClock(1 => 992090288103100417, 50 => 992107917186637897) my clock=EventClock(1 => 992090288103100417, 50 => 992107917186637897)
1782833840.901 sable_history  #x1B[2m2027-06-30T16:43:39.932981Z#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
1782833841.014 sable_history  #x1B[2m2027-06-30T16:52:03.932013Z#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(992110282774093908)), timestamp: 1814374323, clock: EventClock(1 => 992090641066364950, 50 => 992110031111659603, 99 => 992090644996829188), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1814374323 }) }; event clock=EventClock(1 => 992090641066364950, 50 => 992110031111659603, 99 => 992090644996829188) my clock=EventClock(1 => 992090641066364950, 50 => 992110031111659603, 99 => 992090644996829188)
1782833841.014 sable_history  #x1B[2m2027-06-30T16:52:03.933634Z#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
1782833841.110 1: waiting…
1782833841.114 sable_ircd     #x1B[2m2026-06-30T15:37:21.113861Z#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 :synchronize624.332709625
1782833841.114 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize624.332709625
1782833841.117 1: disconnects from server.