--------------------------------- Captured Out ---------------------------------
---- new test ----
1774773380.081 sable_ircd     uid=1001
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.084336Z#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(826011885592645632)), timestamp: 1774773380, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1774773380, name: ServerName("My.Little.Server"), ts: 1774773380, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.084468Z#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
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.084721Z#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(826011885592645632)), timestamp: 1774773380, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1774773380, name: ServerName("My.Little.Server"), ts: 1774773380, 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
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.084882Z#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(826011885592645632)), timestamp: 1774773380, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.084893Z#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(826011885592645633)), timestamp: 1774773380, clock: EventClock(1 => 826011885592645632), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1774773380 }) }; event clock=EventClock(1 => 826011885592645632) my clock=EventClock(1 => 826011885592645632)
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.084922Z#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(826011885592645632)), timestamp: 1774773380, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.084963Z#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
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.084972Z#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(826011885592645632)), timestamp: 1774773380, change: EventComplete(EventComplete), users_to_notify: [] })
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.085003Z#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(826011885592645632)), timestamp: 1774773380, change: EventComplete(EventComplete), users_to_notify: [] }
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.085230Z#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(826011885592645633)), timestamp: 1774773380, change: EventComplete(EventComplete), users_to_notify: [] })
1774773380.085 sable_ircd     #x1B[2m2026-03-29T08:36:20.085264Z#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(826011885592645633)), timestamp: 1774773380, change: EventComplete(EventComplete), users_to_notify: [] }
1774773380.086 sable_ircd     #x1B[2m2026-03-29T08:36:20.086199Z#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
1774773380.177 sable_ircd     #x1B[2m2026-03-29T08:36:20.177034Z#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 }
1774773380.177 sable_ircd     #x1B[2m2026-03-29T08:36:20.177201Z#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
1774773380.177 sable_ircd     #x1B[2m2026-03-29T08:36:20.177249Z#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
1774773380.177 sable_ircd     #x1B[2m2026-03-29T08:36:20.177320Z#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
1774773380.178 sable_ircd     #x1B[2m2026-03-29T08:36:20.177463Z#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
1774773380.178 sable_ircd     #x1B[2m2026-03-29T08:36:20.177532Z#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"
1774773380.178 sable_ircd     #x1B[2m2026-03-29T08:36:20.177681Z#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
1774773380.178 sable_ircd     #x1B[2m2026-03-29T08:36:20.177789Z#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))
1774773380.178 sable_ircd     #x1B[2m2026-03-29T08:36:20.177829Z#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)
1774773380.178 sable_ircd     #x1B[2m2026-03-29T08:36:20.178258Z#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
1774773380.178 sable_ircd     #x1B[2m2026-03-29T08:36:20.178360Z#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
1774773380.179 sable_ircd     #x1B[2m2026-03-29T08:36:20.178575Z#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
1774773380.198 1: connects to server.
1774773380.198 1 -> S: NICK nick1
1774773380.198 1 -> S: USER username * * :Realname
1774773380.198 sable_ircd     #x1B[2m2026-03-29T08:36:20.198358Z#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 }
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.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
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.198548Z#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
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.198620Z#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
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.198819Z#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
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.198872Z#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 nick1"
1774773380.199 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.199049Z#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: 684, tv_nsec: 416967578 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("nick1")), 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
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.199117Z#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
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.199149Z#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
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.199177Z#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"
1774773380.199 sable_ircd     #x1B[2m2026-03-29T08:36:20.199240Z#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: 684, tv_nsec: 416967578 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("nick1")), 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
1774773380.200 sable_ircd     #x1B[2m2026-03-29T08:36:20.199767Z#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
1774773380.200 sable_ircd     #x1B[2m2026-03-29T08:36:20.199810Z#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"))
1774773380.200 sable_ircd     #x1B[2m2026-03-29T08:36:20.199859Z#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
1774773380.200 sable_ircd     #x1B[2m2026-03-29T08:36:20.199893Z#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: 684, tv_nsec: 416967578 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("nick1")), 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
1774773380.200 sable_ircd     #x1B[2m2026-03-29T08:36:20.199957Z#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))
1774773380.200 sable_ircd     #x1B[2m2026-03-29T08:36:20.200201Z#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(826011886079184898)), timestamp: 1774773380, clock: EventClock(1 => 826011885592645633), target: User(UserId(Snowflake(826011886079184896))), details: NewUser(NewUser { nickname: Nickname("nick1"), 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(826011886079184897)), NewUserConnection { user: UserId(Snowflake(826011886079184896)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1774773380 })) }) }; event clock=EventClock(1 => 826011885592645633) my clock=EventClock(1 => 826011885592645633)
1774773380.200 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1774773380.200 sable_ircd     #x1B[2m2026-03-29T08:36:20.200304Z#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
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200571Z#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(826011886079184898)), timestamp: 1774773380, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826011886079184896)), 1) }), users_to_notify: [] })
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200613Z#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(826011886079184898)), timestamp: 1774773380, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826011886079184896)), 1) }), users_to_notify: [] }
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200685Z#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(826011886079184898)), timestamp: 1774773380, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826011886079184896)), 2), connection: UserConnectionId(Snowflake(826011886079184897)) }), users_to_notify: [] })
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200711Z#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(826011886079184898)), timestamp: 1774773380, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826011886079184896)), 2), connection: UserConnectionId(Snowflake(826011886079184897)) }), users_to_notify: [] }
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200754Z#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 nick1 :Welcome to the test Internet Relay Chat network, nick1
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200779Z#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 nick1 :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200799Z#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 nick1 :This server was created 2026-03-29 08:36:20.200792360 UTC
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200819Z#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 nick1 My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200850Z#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 nick1 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
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200868Z#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 nick1 :MOTD File is missing
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200885Z#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): :nick1!username@localhost MODE nick1 +
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200899Z#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 nick1 :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200941Z#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(826011886079184898)), timestamp: 1774773380, change: EventComplete(EventComplete), users_to_notify: [] })
1774773380.201 sable_ircd     #x1B[2m2026-03-29T08:36:20.200962Z#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(826011886079184898)), timestamp: 1774773380, change: EventComplete(EventComplete), users_to_notify: [] }
1774773380.201 S -> 1: :My.Little.Server 001 nick1 :Welcome to the test Internet Relay Chat network, nick1
1774773380.201 S -> 1: :My.Little.Server 002 nick1 :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1774773380.201 1 -> S: PING foo
1774773380.202 sable_ircd     #x1B[2m2026-03-29T08:36:20.201473Z#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
1774773380.202 sable_ircd     #x1B[2m2026-03-29T08:36:20.201510Z#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"
1774773380.202 sable_ircd     #x1B[2m2026-03-29T08:36:20.201576Z#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
1774773380.402 1 -> S: PING synchronize684.619884716
1774773380.402 S -> 1: :My.Little.Server 003 nick1 :This server was created 2026-03-29 08:36:20.200792360 UTC
1774773380.402 S -> 1: :My.Little.Server 004 nick1 My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1774773380.402 S -> 1: :My.Little.Server 005 nick1 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
1774773380.402 S -> 1: :My.Little.Server 422 nick1 :MOTD File is missing
1774773380.402 S -> 1: :nick1!username@localhost MODE nick1 +
1774773380.402 S -> 1: :My.Little.Server NOTICE nick1 :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1774773380.402 S -> 1: :My.Little.Server PONG My.Little.Server :foo
1774773380.402 sable_ircd     #x1B[2m2026-03-29T08:36:20.402045Z#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
1774773380.402 sable_ircd     #x1B[2m2026-03-29T08:36:20.402098Z#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 synchronize684.619884716"
1774773380.402 sable_ircd     #x1B[2m2026-03-29T08:36:20.402227Z#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 :synchronize684.619884716
1774773380.403 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize684.619884716
1774773381.004 2: connects to server.
1774773381.004 2 -> S: NICK nick2
1774773381.004 2 -> S: USER username * * :Realname
1774773381.004 sable_ircd     #x1B[2m2026-03-29T08:36:21.004049Z#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 }
1774773381.004 sable_ircd     #x1B[2m2026-03-29T08:36:21.004206Z#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
1774773381.004 sable_ircd     #x1B[2m2026-03-29T08:36:21.004252Z#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
1774773381.004 sable_ircd     #x1B[2m2026-03-29T08:36:21.004328Z#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
1774773381.005 sable_ircd     #x1B[2m2026-03-29T08:36:21.004512Z#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
1774773381.005 sable_ircd     #x1B[2m2026-03-29T08:36:21.004570Z#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 nick2"
1774773381.005 S -> 2: :My.Little.Server NOTICE * :*** Looking up your hostname
1774773381.005 sable_ircd     #x1B[2m2026-03-29T08:36:21.004773Z#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: 685, tv_nsec: 222671210 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("nick2")), 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
1774773381.005 sable_ircd     #x1B[2m2026-03-29T08:36:21.004835Z#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
1774773381.005 sable_ircd     #x1B[2m2026-03-29T08:36:21.004874Z#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
1774773381.005 sable_ircd     #x1B[2m2026-03-29T08:36:21.004903Z#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"
1774773381.005 sable_ircd     #x1B[2m2026-03-29T08:36:21.004972Z#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: 685, tv_nsec: 222671210 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("nick2")), 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
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.005513Z#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
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.005541Z#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"))
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.005565Z#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
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.005585Z#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: 685, tv_nsec: 222671210 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("nick2")), 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
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.005624Z#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))
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.005873Z#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(826011889455599619)), timestamp: 1774773381, clock: EventClock(1 => 826011886079184898), target: User(UserId(Snowflake(826011889455599618))), details: NewUser(NewUser { nickname: Nickname("nick2"), 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(826011889455599619)), NewUserConnection { user: UserId(Snowflake(826011889455599618)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1774773381 })) }) }; event clock=EventClock(1 => 826011886079184898) my clock=EventClock(1 => 826011886079184898)
1774773381.006 S -> 2: :My.Little.Server NOTICE * :*** Found your hostname: localhost1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.005987Z#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

1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006254Z#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(826011889455599619)), timestamp: 1774773381, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826011889455599618)), 1) }), users_to_notify: [] })
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006288Z#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(826011889455599619)), timestamp: 1774773381, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826011889455599618)), 1) }), users_to_notify: [] }
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006327Z#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(826011889455599619)), timestamp: 1774773381, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826011889455599618)), 2), connection: UserConnectionId(Snowflake(826011889455599619)) }), users_to_notify: [] })
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006347Z#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(826011889455599619)), timestamp: 1774773381, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826011889455599618)), 2), connection: UserConnectionId(Snowflake(826011889455599619)) }), users_to_notify: [] }
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006391Z#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 nick2 :Welcome to the test Internet Relay Chat network, nick2
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006417Z#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 nick2 :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006438Z#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 nick2 :This server was created 2026-03-29 08:36:21.006430281 UTC
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006455Z#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 nick2 My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1774773381.006 sable_ircd     #x1B[2m2026-03-29T08:36:21.006475Z#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 nick2 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
1774773381.007 sable_ircd     #x1B[2m2026-03-29T08:36:21.006492Z#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 nick2 :MOTD File is missing
1774773381.007 sable_ircd     #x1B[2m2026-03-29T08:36:21.006510Z#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): :nick2!username@localhost MODE nick2 +
1774773381.007 sable_ircd     #x1B[2m2026-03-29T08:36:21.006524Z#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 nick2 :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1774773381.007 sable_ircd     #x1B[2m2026-03-29T08:36:21.006566Z#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(826011889455599619)), timestamp: 1774773381, change: EventComplete(EventComplete), users_to_notify: [] })
1774773381.007 sable_ircd     #x1B[2m2026-03-29T08:36:21.006588Z#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(826011889455599619)), timestamp: 1774773381, change: EventComplete(EventComplete), users_to_notify: [] }
1774773381.007 S -> 2: :My.Little.Server 001 nick2 :Welcome to the test Internet Relay Chat network, nick2
1774773381.007 S -> 2: :My.Little.Server 002 nick2 :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1774773381.007 2 -> S: PING foo
1774773381.007 sable_ircd     #x1B[2m2026-03-29T08:36:21.007169Z#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
1774773381.007 sable_ircd     #x1B[2m2026-03-29T08:36:21.007217Z#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"
1774773381.007 sable_ircd     #x1B[2m2026-03-29T08:36:21.007309Z#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
1774773381.207 2 -> S: PING synchronize685.425585288
1774773381.207 S -> 2: :My.Little.Server 003 nick2 :This server was created 2026-03-29 08:36:21.006430281 UTC
1774773381.207 S -> 2: :My.Little.Server 004 nick2 My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1774773381.207 S -> 2: :My.Little.Server 005 nick2 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
1774773381.207 S -> 2: :My.Little.Server 422 nick2 :MOTD File is missing
1774773381.207 S -> 2: :nick2!username@localhost MODE nick2 +
1774773381.207 S -> 2: :My.Little.Server NOTICE nick2 :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1774773381.207 S -> 2: :My.Little.Server PONG My.Little.Server :foo
1774773381.208 sable_ircd     #x1B[2m2026-03-29T08:36:21.207851Z#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
1774773381.208 sable_ircd     #x1B[2m2026-03-29T08:36:21.207926Z#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 synchronize685.425585288"
1774773381.208 sable_ircd     #x1B[2m2026-03-29T08:36:21.208075Z#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 :synchronize685.425585288
1774773381.208 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize685.425585288
1774773381.809 2 -> S: JOIN #chan1
1774773381.809 2 -> S: JOIN #chan2
1774773381.809 2 -> S: AWAY :I'm on a break
1774773381.810 sable_ircd     #x1B[2m2026-03-29T08:36:21.809963Z#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
1774773381.810 sable_ircd     #x1B[2m2026-03-29T08:36:21.810050Z#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"JOIN #chan1"
1774773381.810 sable_ircd     #x1B[2m2026-03-29T08:36:21.810166Z#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
1774773381.810 sable_ircd     #x1B[2m2026-03-29T08:36:21.810212Z#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"JOIN #chan2"
1774773381.810 sable_ircd     #x1B[2m2026-03-29T08:36:21.810285Z#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
1774773381.810 sable_ircd     #x1B[2m2026-03-29T08:36:21.810327Z#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"AWAY :I'm on a break"
1774773381.910 2 -> S: PING synchronize686.127979942
1774773381.910 sable_ircd     #x1B[2m2026-03-29T08:36:21.910332Z#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
1774773381.910 sable_ircd     #x1B[2m2026-03-29T08:36:21.910406Z#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 synchronize686.127979942"
1774773382.162 sable_ircd     #x1B[2m2026-03-29T08:36:22.162082Z#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(826011894308409348)), timestamp: 1774773382, clock: EventClock(1 => 826011889455599619), target: Channel(ChannelId(Snowflake(826011894304215044))), details: NewChannel(NewChannel { name: ChannelName("#chan1"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 826011889455599619) my clock=EventClock(1 => 826011889455599619)
1774773382.162 sable_ircd     #x1B[2m2026-03-29T08:36:22.162257Z#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
1774773382.162 sable_ircd     #x1B[2m2026-03-29T08:36:22.162339Z#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(826011894308409349)), timestamp: 1774773382, clock: EventClock(1 => 826011894308409348), target: Membership(MembershipId(UserId(Snowflake(826011889455599618)), ChannelId(Snowflake(826011894304215044)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(826011894304215044)), user: UserId(Snowflake(826011889455599618)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 826011894308409348) my clock=EventClock(1 => 826011894308409348)
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162456Z#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
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162486Z#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
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162541Z#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(826011894308409348)), timestamp: 1774773382, change: EventComplete(EventComplete), users_to_notify: [] })
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162582Z#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(826011894308409348)), timestamp: 1774773382, change: EventComplete(EventComplete), users_to_notify: [] }
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162716Z#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(826011894308409349)), timestamp: 1774773382, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826011889455599618)), ChannelId(Snowflake(826011894304215044))), user: HistoricUserId(UserId(Snowflake(826011889455599618)), 2) }), users_to_notify: [UserId(Snowflake(826011889455599618))] })
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162753Z#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(826011894308409349)), timestamp: 1774773382, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826011889455599618)), ChannelId(Snowflake(826011894304215044))), user: HistoricUserId(UserId(Snowflake(826011889455599618)), 2) }), users_to_notify: [UserId(Snowflake(826011889455599618))] }
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162800Z#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): :nick2!username@localhost JOIN #chan1
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162838Z#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): :nick2!username@localhost MODE #chan1 +o nick2
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162872Z#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 353 nick2 = #chan1 :@nick2
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162891Z#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 366 nick2 #chan1 :End of names list
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162928Z#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(826011894308409349)), timestamp: 1774773382, change: EventComplete(EventComplete), users_to_notify: [] })
1774773382.163 sable_ircd     #x1B[2m2026-03-29T08:36:22.162949Z#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(826011894308409349)), timestamp: 1774773382, change: EventComplete(EventComplete), users_to_notify: [] }
1774773382.163 S -> 2: :nick2!username@localhost JOIN #chan1
1774773382.163 S -> 2: :nick2!username@localhost MODE #chan1 +o nick2
1774773382.163 S -> 2: :My.Little.Server 353 nick2 = #chan1 :@nick2
1774773382.163 S -> 2: :My.Little.Server 366 nick2 #chan1 :End of names list
1774773383.164 2: waiting…
1774773383.168 sable_ircd     #x1B[2m2026-03-29T08:36:23.167911Z#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(826011898523684870)), timestamp: 1774773383, clock: EventClock(1 => 826011894308409349), target: Channel(ChannelId(Snowflake(826011898523684869))), details: NewChannel(NewChannel { name: ChannelName("#chan2"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 826011894308409349) my clock=EventClock(1 => 826011894308409349)
1774773383.168 sable_ircd     #x1B[2m2026-03-29T08:36:23.168138Z#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
1774773383.168 sable_ircd     #x1B[2m2026-03-29T08:36:23.168243Z#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(826011898527879175)), timestamp: 1774773383, clock: EventClock(1 => 826011898523684870), target: Membership(MembershipId(UserId(Snowflake(826011889455599618)), ChannelId(Snowflake(826011898523684869)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(826011898523684869)), user: UserId(Snowflake(826011889455599618)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 826011898523684870) my clock=EventClock(1 => 826011898523684870)
1774773383.168 sable_ircd     #x1B[2m2026-03-29T08:36:23.168402Z#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
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168473Z#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
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168734Z#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(826011898523684870)), timestamp: 1774773383, change: EventComplete(EventComplete), users_to_notify: [] })
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168768Z#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(826011898523684870)), timestamp: 1774773383, change: EventComplete(EventComplete), users_to_notify: [] }
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168810Z#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(826011898527879175)), timestamp: 1774773383, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826011889455599618)), ChannelId(Snowflake(826011898523684869))), user: HistoricUserId(UserId(Snowflake(826011889455599618)), 2) }), users_to_notify: [UserId(Snowflake(826011889455599618))] })
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168830Z#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(826011898527879175)), timestamp: 1774773383, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826011889455599618)), ChannelId(Snowflake(826011898523684869))), user: HistoricUserId(UserId(Snowflake(826011889455599618)), 2) }), users_to_notify: [UserId(Snowflake(826011889455599618))] }
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168883Z#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): :nick2!username@localhost JOIN #chan2
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168921Z#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): :nick2!username@localhost MODE #chan2 +o nick2
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168956Z#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 353 nick2 = #chan2 :@nick2
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.168975Z#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 366 nick2 #chan2 :End of names list
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.169013Z#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(826011898527879175)), timestamp: 1774773383, change: EventComplete(EventComplete), users_to_notify: [] })
1774773383.169 sable_ircd     #x1B[2m2026-03-29T08:36:23.169034Z#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(826011898527879175)), timestamp: 1774773383, change: EventComplete(EventComplete), users_to_notify: [] }
1774773383.169 S -> 2: :nick2!username@localhost JOIN #chan2
1774773383.169 S -> 2: :nick2!username@localhost MODE #chan2 +o nick2
1774773383.169 S -> 2: :My.Little.Server 353 nick2 = #chan2 :@nick2
1774773383.169 S -> 2: :My.Little.Server 366 nick2 #chan2 :End of names list
1774773384.171 2: waiting…
1774773384.174 sable_ircd     #x1B[2m2026-03-29T08:36:24.173964Z#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(826011902743154696)), timestamp: 1774773384, clock: EventClock(1 => 826011898527879175), target: User(UserId(Snowflake(826011889455599618))), details: UserAway(UserAway { reason: Some(AwayReason("I'm on a break")) }) }; event clock=EventClock(1 => 826011898527879175) my clock=EventClock(1 => 826011898527879175)
1774773384.174 sable_ircd     #x1B[2m2026-03-29T08:36:24.174146Z#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
1774773384.174 sable_ircd     #x1B[2m2026-03-29T08:36:24.174158Z#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
1774773384.174 sable_ircd     #x1B[2m2026-03-29T08:36:24.174430Z#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(826011902743154696)), timestamp: 1774773384, change: UserAwayChange(UserAwayChange { user: HistoricUserId(UserId(Snowflake(826011889455599618)), 3), old_reason: None, new_reason: Some(AwayReason("I'm on a break")) }), users_to_notify: [UserId(Snowflake(826011889455599618))] })
1774773384.175 sable_ircd     #x1B[2m2026-03-29T08:36:24.174478Z#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(826011902743154696)), timestamp: 1774773384, change: UserAwayChange(UserAwayChange { user: HistoricUserId(UserId(Snowflake(826011889455599618)), 3), old_reason: None, new_reason: Some(AwayReason("I'm on a break")) }), users_to_notify: [UserId(Snowflake(826011889455599618))] }
1774773384.175 sable_ircd     #x1B[2m2026-03-29T08:36:24.174535Z#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 306 nick2 :You have been marked as being away
1774773384.175 sable_ircd     #x1B[2m2026-03-29T08:36:24.174615Z#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(826011902743154696)), timestamp: 1774773384, change: EventComplete(EventComplete), users_to_notify: [] })
1774773384.175 sable_ircd     #x1B[2m2026-03-29T08:36:24.174671Z#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(826011902743154696)), timestamp: 1774773384, change: EventComplete(EventComplete), users_to_notify: [] }
1774773384.175 S -> 2: :My.Little.Server 306 nick2 :You have been marked as being away
1774773385.176 2: waiting…
1774773385.180 sable_ircd     #x1B[2m2026-03-29T08:36:25.180262Z#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 :synchronize686.127979942
1774773385.181 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize686.127979942
1774773385.281 1 -> S: PING synchronize689.49924096
1774773385.282 sable_ircd     #x1B[2m2026-03-29T08:36:25.281528Z#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
1774773385.282 sable_ircd     #x1B[2m2026-03-29T08:36:25.281602Z#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 synchronize689.49924096"
1774773385.282 sable_ircd     #x1B[2m2026-03-29T08:36:25.281782Z#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 :synchronize689.49924096
1774773385.282 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize689.49924096
1774773385.282 1 -> S: WHOIS nick2
1774773385.282 sable_ircd     #x1B[2m2026-03-29T08:36:25.282392Z#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
1774773385.282 sable_ircd     #x1B[2m2026-03-29T08:36:25.282438Z#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"WHOIS nick2"
1774773385.283 sable_ircd     #x1B[2m2026-03-29T08:36:25.282544Z#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 311 nick1 nick2 username localhost * :Realname
1774773385.283 sable_ircd     #x1B[2m2026-03-29T08:36:25.282587Z#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 301 nick1 nick2 :I'm on a break
1774773385.283 sable_ircd     #x1B[2m2026-03-29T08:36:25.282623Z#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 318 nick1 nick2 :End of /WHOIS
1774773385.382 1 -> S: PING synchronize689.600669254
1774773385.382 S -> 1: :My.Little.Server 311 nick1 nick2 username localhost * :Realname
1774773385.382 S -> 1: :My.Little.Server 301 nick1 nick2 :I'm on a break
1774773385.382 S -> 1: :My.Little.Server 318 nick1 nick2 :End of /WHOIS
1774773385.383 sable_ircd     #x1B[2m2026-03-29T08:36:25.382769Z#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
1774773385.383 sable_ircd     #x1B[2m2026-03-29T08:36:25.382833Z#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 synchronize689.600669254"
1774773385.383 sable_ircd     #x1B[2m2026-03-29T08:36:25.382944Z#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 :synchronize689.600669254
1774773385.383 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize689.600669254
1774773385.386 1: disconnects from server.
1774773385.386 2: disconnects from server.