--------------------------------- Captured Out ---------------------------------
---- new test ----
1782833562.361 sable_ircd     uid=1001
1782833562.366 sable_ircd     #x1B[2m2026-06-30T15:32:42.365190Z#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(859818740374573056)), timestamp: 1782833562, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1782833562, name: ServerName("My.Little.Server"), ts: 1782833562, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1782833562.366 sable_ircd     #x1B[2m2026-06-30T15:32:42.365320Z#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
1782833562.366 sable_ircd     #x1B[2m2026-06-30T15:32:42.365409Z#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
1782833562.366 sable_ircd     #x1B[2m2026-06-30T15:32:42.365576Z#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(859818740374573056)), timestamp: 1782833562, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1782833562, name: ServerName("My.Little.Server"), ts: 1782833562, 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
1782833562.366 sable_ircd     #x1B[2m2026-06-30T15:32:42.365706Z#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(859818740374573056)), timestamp: 1782833562, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1782833562.366 sable_ircd     #x1B[2m2026-06-30T15:32:42.365749Z#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(859818740374573056)), timestamp: 1782833562, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1782833562.366 sable_ircd     #x1B[2m2026-06-30T15:32:42.365814Z#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(859818740374573056)), timestamp: 1782833562, change: EventComplete(EventComplete), users_to_notify: [] })
1782833562.366 sable_ircd     #x1B[2m2026-06-30T15:32:42.365883Z#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(859818740374573056)), timestamp: 1782833562, change: EventComplete(EventComplete), users_to_notify: [] }
1782833562.367 sable_ircd     #x1B[2m2026-06-30T15:32:42.366704Z#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(859818740378767361)), timestamp: 1782833562, clock: EventClock(1 => 859818740374573056), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1782833562 }) }; event clock=EventClock(1 => 859818740374573056) my clock=EventClock(1 => 859818740374573056)
1782833562.367 sable_ircd     #x1B[2m2026-06-30T15:32:42.366813Z#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
1782833562.367 sable_ircd     #x1B[2m2026-06-30T15:32:42.367075Z#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(859818740378767361)), timestamp: 1782833562, change: EventComplete(EventComplete), users_to_notify: [] })
1782833562.367 sable_ircd     #x1B[2m2026-06-30T15:32:42.367141Z#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(859818740378767361)), timestamp: 1782833562, change: EventComplete(EventComplete), users_to_notify: [] }
1782833562.457 sable_ircd     #x1B[2m2026-06-30T15:32:42.457406Z#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 }
1782833562.458 sable_ircd     #x1B[2m2026-06-30T15:32:42.457567Z#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
1782833562.458 sable_ircd     #x1B[2m2026-06-30T15:32:42.457636Z#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
1782833562.458 sable_ircd     #x1B[2m2026-06-30T15:32:42.457721Z#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
1782833562.458 sable_ircd     #x1B[2m2026-06-30T15:32:42.457892Z#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
1782833562.458 sable_ircd     #x1B[2m2026-06-30T15:32:42.457967Z#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"
1782833562.458 sable_ircd     #x1B[2m2026-06-30T15:32:42.458120Z#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
1782833562.458 sable_ircd     #x1B[2m2026-06-30T15:32:42.458220Z#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))
1782833562.458 sable_ircd     #x1B[2m2026-06-30T15:32:42.458268Z#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)
1782833562.459 sable_ircd     #x1B[2m2026-06-30T15:32:42.458786Z#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
1782833562.459 sable_ircd     #x1B[2m2026-06-30T15:32:42.458865Z#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
1782833562.459 sable_ircd     #x1B[2m2026-06-30T15:32:42.459046Z#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
1782833562.478 1: connects to server.
1782833562.479 1 -> S: NICK bar
1782833562.479 1 -> S: USER username * * :Realname
1782833562.479 sable_ircd     #x1B[2m2026-06-30T15:32:42.478910Z#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 }
1782833562.479 sable_ircd     #x1B[2m2026-06-30T15:32:42.479072Z#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
1782833562.479 sable_ircd     #x1B[2m2026-06-30T15:32:42.479118Z#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
1782833562.479 sable_ircd     #x1B[2m2026-06-30T15:32:42.479193Z#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
1782833562.479 sable_ircd     #x1B[2m2026-06-30T15:32:42.479423Z#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
1782833562.480 sable_ircd     #x1B[2m2026-06-30T15:32:42.479500Z#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 bar"
1782833562.480 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1782833562.480 sable_ircd     #x1B[2m2026-06-30T15:32:42.479731Z#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: 346, tv_nsec: 702811309 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("bar")), realname: OnceLock(<uninit>), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1782833562.480 sable_ircd     #x1B[2m2026-06-30T15:32:42.479824Z#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
1782833562.480 sable_ircd     #x1B[2m2026-06-30T15:32:42.479878Z#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
1782833562.480 sable_ircd     #x1B[2m2026-06-30T15:32:42.479911Z#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"
1782833562.480 sable_ircd     #x1B[2m2026-06-30T15:32:42.479983Z#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: 346, tv_nsec: 702811309 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("bar")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.480520Z#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
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.480563Z#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"))
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.480593Z#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
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.480614Z#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: 346, tv_nsec: 702811309 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("bar")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.480656Z#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))
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.480922Z#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(859818740856918018)), timestamp: 1782833562, clock: EventClock(1 => 859818740378767361), target: User(UserId(Snowflake(859818740856918016))), details: NewUser(NewUser { nickname: Nickname("bar"), username: Username("username"), visible_hostname: Hostname("localhost"), realname: Realname("Realname"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(859818740856918017)), NewUserConnection { user: UserId(Snowflake(859818740856918016)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1782833562 })) }) }; event clock=EventClock(1 => 859818740378767361) my clock=EventClock(1 => 859818740378767361)
1782833562.481 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.481044Z#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
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.481359Z#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(859818740856918018)), timestamp: 1782833562, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(859818740856918016)), 1) }), users_to_notify: [] })
1782833562.481 sable_ircd     #x1B[2m2026-06-30T15:32:42.481434Z#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(859818740856918018)), timestamp: 1782833562, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(859818740856918016)), 1) }), users_to_notify: [] }
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481503Z#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(859818740856918018)), timestamp: 1782833562, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(859818740856918016)), 2), connection: UserConnectionId(Snowflake(859818740856918017)) }), users_to_notify: [] })
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481538Z#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(859818740856918018)), timestamp: 1782833562, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(859818740856918016)), 2), connection: UserConnectionId(Snowflake(859818740856918017)) }), users_to_notify: [] }
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481612Z#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 bar :Welcome to the test Internet Relay Chat network, bar
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481657Z#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 bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481704Z#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 bar :This server was created 2026-06-30 15:32:42.481688296 UTC
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481742Z#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 bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481804Z#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 bar EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481841Z#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 bar :MOTD File is missing
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481891Z#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): :bar!username@localhost MODE bar +
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481925Z#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 bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.481993Z#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(859818740856918018)), timestamp: 1782833562, change: EventComplete(EventComplete), users_to_notify: [] })
1782833562.482 sable_ircd     #x1B[2m2026-06-30T15:32:42.482028Z#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(859818740856918018)), timestamp: 1782833562, change: EventComplete(EventComplete), users_to_notify: [] }
1782833562.482 S -> 1: :My.Little.Server 001 bar :Welcome to the test Internet Relay Chat network, bar
1782833562.482 1 -> S: PING foo
1782833562.483 sable_ircd     #x1B[2m2026-06-30T15:32:42.482726Z#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
1782833562.483 sable_ircd     #x1B[2m2026-06-30T15:32:42.482768Z#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"
1782833562.483 sable_ircd     #x1B[2m2026-06-30T15:32:42.482836Z#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
1782833562.583 1 -> S: PING synchronize346.806285284
1782833562.583 S -> 1: :My.Little.Server 002 bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1782833562.583 S -> 1: :My.Little.Server 003 bar :This server was created 2026-06-30 15:32:42.481688296 UTC
1782833562.583 S -> 1: :My.Little.Server 004 bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1782833562.583 S -> 1: :My.Little.Server 005 bar EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server
1782833562.583 S -> 1: :My.Little.Server 422 bar :MOTD File is missing
1782833562.583 S -> 1: :bar!username@localhost MODE bar +
1782833562.583 S -> 1: :My.Little.Server NOTICE bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1782833562.583 S -> 1: :My.Little.Server PONG My.Little.Server :foo
1782833562.583 sable_ircd     #x1B[2m2026-06-30T15:32:42.583305Z#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
1782833562.583 sable_ircd     #x1B[2m2026-06-30T15:32:42.583423Z#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 synchronize346.806285284"
1782833562.584 sable_ircd     #x1B[2m2026-06-30T15:32:42.583573Z#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 :synchronize346.806285284
1782833562.584 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize346.806285284
1782833563.385 1 -> S: PING synchronize347.608706136
1782833563.386 sable_ircd     #x1B[2m2026-06-30T15:32:43.385745Z#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
1782833563.386 sable_ircd     #x1B[2m2026-06-30T15:32:43.385822Z#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 synchronize347.608706136"
1782833563.386 sable_ircd     #x1B[2m2026-06-30T15:32:43.385969Z#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 :synchronize347.608706136
1782833563.386 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize347.608706136
1782833563.386 1 -> S: AWAY :I'm not here right now
1782833563.387 sable_ircd     #x1B[2m2026-06-30T15:32:43.386717Z#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
1782833563.387 sable_ircd     #x1B[2m2026-06-30T15:32:43.386771Z#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"AWAY :I'm not here right now"
1782833563.487 1 -> S: PING synchronize347.710229599
1782833563.487 sable_ircd     #x1B[2m2026-06-30T15:32:43.487202Z#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
1782833563.487 sable_ircd     #x1B[2m2026-06-30T15:32:43.487290Z#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 synchronize347.710229599"
1782833564.242 sable_ircd     #x1B[2m2026-06-30T15:32:44.241581Z#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(859818748243087363)), timestamp: 1782833564, clock: EventClock(1 => 859818740856918018), target: User(UserId(Snowflake(859818740856918016))), details: UserAway(UserAway { reason: Some(AwayReason("I'm not here right now")) }) }; event clock=EventClock(1 => 859818740856918018) my clock=EventClock(1 => 859818740856918018)
1782833564.242 sable_ircd     #x1B[2m2026-06-30T15:32:44.241802Z#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
1782833564.242 sable_ircd     #x1B[2m2026-06-30T15:32:44.241807Z#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
1782833564.242 sable_ircd     #x1B[2m2026-06-30T15:32:44.242083Z#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(859818748243087363)), timestamp: 1782833564, change: UserAwayChange(UserAwayChange { user: HistoricUserId(UserId(Snowflake(859818740856918016)), 3), old_reason: None, new_reason: Some(AwayReason("I'm not here right now")) }), users_to_notify: [UserId(Snowflake(859818740856918016))] })
1782833564.242 sable_ircd     #x1B[2m2026-06-30T15:32:44.242137Z#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(859818748243087363)), timestamp: 1782833564, change: UserAwayChange(UserAwayChange { user: HistoricUserId(UserId(Snowflake(859818740856918016)), 3), old_reason: None, new_reason: Some(AwayReason("I'm not here right now")) }), users_to_notify: [UserId(Snowflake(859818740856918016))] }
1782833564.242 sable_ircd     #x1B[2m2026-06-30T15:32:44.242198Z#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 306 bar :You have been marked as being away
1782833564.242 sable_ircd     #x1B[2m2026-06-30T15:32:44.242289Z#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(859818748243087363)), timestamp: 1782833564, change: EventComplete(EventComplete), users_to_notify: [] })
1782833564.242 sable_ircd     #x1B[2m2026-06-30T15:32:44.242325Z#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(859818748243087363)), timestamp: 1782833564, change: EventComplete(EventComplete), users_to_notify: [] }
1782833564.243 S -> 1: :My.Little.Server 306 bar :You have been marked as being away
1782833565.244 1: waiting…
1782833565.247 sable_ircd     #x1B[2m2026-06-30T15:32:45.247183Z#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 :synchronize347.710229599
1782833565.248 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize347.710229599
1782833565.248 2: connects to server.
1782833565.248 2 -> S: NICK qux
1782833565.248 2 -> S: USER username * * :Realname
1782833565.248 sable_ircd     #x1B[2m2026-06-30T15:32:45.248176Z#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 }
1782833565.248 sable_ircd     #x1B[2m2026-06-30T15:32:45.248307Z#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
1782833565.248 sable_ircd     #x1B[2m2026-06-30T15:32:45.248392Z#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
1782833565.249 sable_ircd     #x1B[2m2026-06-30T15:32:45.248470Z#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
1782833565.249 sable_ircd     #x1B[2m2026-06-30T15:32:45.248651Z#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
1782833565.249 sable_ircd     #x1B[2m2026-06-30T15:32:45.248705Z#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 qux"
1782833565.249 S -> 2: :My.Little.Server NOTICE * :*** Looking up your hostname
1782833565.249 sable_ircd     #x1B[2m2026-06-30T15:32:45.248863Z#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: 349, tv_nsec: 472096483 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("qux")), 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
1782833565.249 sable_ircd     #x1B[2m2026-06-30T15:32:45.248931Z#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
1782833565.249 sable_ircd     #x1B[2m2026-06-30T15:32:45.248975Z#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
1782833565.249 sable_ircd     #x1B[2m2026-06-30T15:32:45.249030Z#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"
1782833565.249 sable_ircd     #x1B[2m2026-06-30T15:32:45.249134Z#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: 349, tv_nsec: 472096483 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("qux")), 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
1782833565.250 sable_ircd     #x1B[2m2026-06-30T15:32:45.249738Z#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
1782833565.250 sable_ircd     #x1B[2m2026-06-30T15:32:45.249769Z#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"))
1782833565.250 sable_ircd     #x1B[2m2026-06-30T15:32:45.249793Z#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
1782833565.250 sable_ircd     #x1B[2m2026-06-30T15:32:45.249811Z#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: 349, tv_nsec: 472096483 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("qux")), 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
1782833565.250 sable_ircd     #x1B[2m2026-06-30T15:32:45.249853Z#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))
1782833565.250 S -> 2: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1782833565.250 sable_ircd     #x1B[2m2026-06-30T15:32:45.250061Z#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(859818752475140100)), timestamp: 1782833565, clock: EventClock(1 => 859818748243087363), target: User(UserId(Snowflake(859818752470945794))), details: NewUser(NewUser { nickname: Nickname("qux"), 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(859818752470945795)), NewUserConnection { user: UserId(Snowflake(859818752470945794)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1782833565 })) }) }; event clock=EventClock(1 => 859818748243087363) my clock=EventClock(1 => 859818748243087363)
1782833565.250 sable_ircd     #x1B[2m2026-06-30T15:32:45.250172Z#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
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250472Z#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(859818752475140100)), timestamp: 1782833565, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(859818752470945794)), 1) }), users_to_notify: [] })
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250513Z#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(859818752475140100)), timestamp: 1782833565, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(859818752470945794)), 1) }), users_to_notify: [] }
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250553Z#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(859818752475140100)), timestamp: 1782833565, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(859818752470945794)), 2), connection: UserConnectionId(Snowflake(859818752470945795)) }), users_to_notify: [] })
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250574Z#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(859818752475140100)), timestamp: 1782833565, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(859818752470945794)), 2), connection: UserConnectionId(Snowflake(859818752470945795)) }), users_to_notify: [] }
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250615Z#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 qux :Welcome to the test Internet Relay Chat network, qux
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250640Z#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 qux :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250662Z#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 qux :This server was created 2026-06-30 15:32:45.250654062 UTC
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250679Z#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 qux My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250700Z#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 qux 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
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250715Z#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 qux :MOTD File is missing
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250732Z#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): :qux!username@localhost MODE qux +
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250746Z#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 qux :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250774Z#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(859818752475140100)), timestamp: 1782833565, change: EventComplete(EventComplete), users_to_notify: [] })
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.250794Z#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(859818752475140100)), timestamp: 1782833565, change: EventComplete(EventComplete), users_to_notify: [] }
1782833565.251 S -> 2: :My.Little.Server 001 qux :Welcome to the test Internet Relay Chat network, qux
1782833565.251 S -> 2: :My.Little.Server 002 qux :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1782833565.251 2 -> S: PING foo
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.251339Z#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
1782833565.251 sable_ircd     #x1B[2m2026-06-30T15:32:45.251413Z#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"
1782833565.252 sable_ircd     #x1B[2m2026-06-30T15:32:45.251485Z#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
1782833565.451 2 -> S: PING synchronize349.67500997
1782833565.452 S -> 2: :My.Little.Server 003 qux :This server was created 2026-06-30 15:32:45.250654062 UTC
1782833565.452 S -> 2: :My.Little.Server 004 qux My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1782833565.452 S -> 2: :My.Little.Server 005 qux 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
1782833565.452 S -> 2: :My.Little.Server 422 qux :MOTD File is missing
1782833565.452 S -> 2: :qux!username@localhost MODE qux +
1782833565.452 S -> 2: :My.Little.Server NOTICE qux :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1782833565.452 S -> 2: :My.Little.Server PONG My.Little.Server :foo
1782833565.452 sable_ircd     #x1B[2m2026-06-30T15:32:45.452061Z#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
1782833565.452 sable_ircd     #x1B[2m2026-06-30T15:32:45.452150Z#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 synchronize349.67500997"
1782833565.452 sable_ircd     #x1B[2m2026-06-30T15:32:45.452312Z#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 :synchronize349.67500997
1782833565.453 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize349.67500997
1782833566.054 2 -> S: PRIVMSG bar :what's up
1782833566.054 sable_ircd     #x1B[2m2026-06-30T15:32:46.054094Z#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
1782833566.054 sable_ircd     #x1B[2m2026-06-30T15:32:46.054165Z#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"PRIVMSG bar :what's up"
1782833566.054 sable_ircd     #x1B[2m2026-06-30T15:32:46.054393Z#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 301 qux bar :I'm not here right now
1782833566.055 sable_ircd     #x1B[2m2026-06-30T15:32:46.054571Z#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(859818755847360517)), timestamp: 1782833566, clock: EventClock(1 => 859818752475140100), target: Message(MessageId(Uuid7(019f1929-5166-7a90-9736-ec7f71a56b7d))), details: NewMessage(NewMessage { source: UserId(Snowflake(859818752470945794)), target: User(UserId(Snowflake(859818740856918016))), message_type: Privmsg, text: "what's up" }) }; event clock=EventClock(1 => 859818752475140100) my clock=EventClock(1 => 859818752475140100)
1782833566.055 sable_ircd     #x1B[2m2026-06-30T15:32:46.054757Z#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
1782833566.055 sable_ircd     #x1B[2m2026-06-30T15:32:46.054839Z#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
1782833566.055 sable_ircd     #x1B[2m2026-06-30T15:32:46.055099Z#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(859818755847360517)), timestamp: 1782833566, change: NewMessage(NewMessage { message: MessageId(Uuid7(019f1929-5166-7a90-9736-ec7f71a56b7d)), source: User(HistoricUserId(UserId(Snowflake(859818752470945794)), 2)), target: User(HistoricUserId(UserId(Snowflake(859818740856918016)), 3)) }), users_to_notify: [UserId(Snowflake(859818740856918016)), UserId(Snowflake(859818752470945794))] })
1782833566.055 sable_ircd     #x1B[2m2026-06-30T15:32:46.055138Z#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(859818755847360517)), timestamp: 1782833566, change: NewMessage(NewMessage { message: MessageId(Uuid7(019f1929-5166-7a90-9736-ec7f71a56b7d)), source: User(HistoricUserId(UserId(Snowflake(859818752470945794)), 2)), target: User(HistoricUserId(UserId(Snowflake(859818740856918016)), 3)) }), users_to_notify: [UserId(Snowflake(859818740856918016)), UserId(Snowflake(859818752470945794))] }
1782833566.055 sable_ircd     #x1B[2m2026-06-30T15:32:46.055196Z#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): :qux!username@localhost PRIVMSG bar :what's up
1782833566.055 sable_ircd     #x1B[2m2026-06-30T15:32:46.055256Z#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(859818755847360517)), timestamp: 1782833566, change: EventComplete(EventComplete), users_to_notify: [] })
1782833566.055 sable_ircd     #x1B[2m2026-06-30T15:32:46.055292Z#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(859818755847360517)), timestamp: 1782833566, change: EventComplete(EventComplete), users_to_notify: [] }
1782833566.154 2 -> S: PING synchronize350.377395111
1782833566.154 S -> 2: :My.Little.Server 301 qux bar :I'm not here right now
1782833566.155 sable_ircd     #x1B[2m2026-06-30T15:32:46.154444Z#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
1782833566.155 sable_ircd     #x1B[2m2026-06-30T15:32:46.154515Z#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 synchronize350.377395111"
1782833567.155 2: waiting…
1782833567.159 sable_ircd     #x1B[2m2026-06-30T15:32:47.159095Z#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 :synchronize350.377395111
1782833567.159 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize350.377395111
1782833567.260 1 -> S: PING synchronize351.483322076
1782833567.260 S -> 1: :qux!username@localhost PRIVMSG bar :what's up
1782833567.260 sable_ircd     #x1B[2m2026-06-30T15:32:47.260226Z#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
1782833567.260 sable_ircd     #x1B[2m2026-06-30T15:32:47.260282Z#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 synchronize351.483322076"
1782833567.260 sable_ircd     #x1B[2m2026-06-30T15:32:47.260439Z#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 :synchronize351.483322076
1782833567.261 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize351.483322076
1782833567.261 1 -> S: AWAY
1782833567.261 sable_ircd     #x1B[2m2026-06-30T15:32:47.261058Z#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
1782833567.261 sable_ircd     #x1B[2m2026-06-30T15:32:47.261098Z#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"AWAY"
1782833567.261 sable_ircd     #x1B[2m2026-06-30T15:32:47.261290Z#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(859818760909885446)), timestamp: 1782833567, clock: EventClock(1 => 859818755847360517), target: User(UserId(Snowflake(859818740856918016))), details: UserAway(UserAway { reason: None }) }; event clock=EventClock(1 => 859818755847360517) my clock=EventClock(1 => 859818755847360517)
1782833567.262 sable_ircd     #x1B[2m2026-06-30T15:32:47.261463Z#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
1782833567.262 sable_ircd     #x1B[2m2026-06-30T15:32:47.261485Z#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
1782833567.262 sable_ircd     #x1B[2m2026-06-30T15:32:47.261736Z#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(859818760909885446)), timestamp: 1782833567, change: UserAwayChange(UserAwayChange { user: HistoricUserId(UserId(Snowflake(859818740856918016)), 4), old_reason: Some(AwayReason("I'm not here right now")), new_reason: None }), users_to_notify: [UserId(Snowflake(859818740856918016))] })
1782833567.262 sable_ircd     #x1B[2m2026-06-30T15:32:47.261774Z#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(859818760909885446)), timestamp: 1782833567, change: UserAwayChange(UserAwayChange { user: HistoricUserId(UserId(Snowflake(859818740856918016)), 4), old_reason: Some(AwayReason("I'm not here right now")), new_reason: None }), users_to_notify: [UserId(Snowflake(859818740856918016))] }
1782833567.262 sable_ircd     #x1B[2m2026-06-30T15:32:47.261812Z#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 305 bar :You are no longer marked as being away
1782833567.262 sable_ircd     #x1B[2m2026-06-30T15:32:47.261860Z#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(859818760909885446)), timestamp: 1782833567, change: EventComplete(EventComplete), users_to_notify: [] })
1782833567.262 sable_ircd     #x1B[2m2026-06-30T15:32:47.261881Z#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(859818760909885446)), timestamp: 1782833567, change: EventComplete(EventComplete), users_to_notify: [] }
1782833567.361 1 -> S: PING synchronize351.584592415
1782833567.361 S -> 1: :My.Little.Server 305 bar :You are no longer marked as being away
1782833567.362 sable_ircd     #x1B[2m2026-06-30T15:32:47.361541Z#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
1782833567.362 sable_ircd     #x1B[2m2026-06-30T15:32:47.361610Z#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 synchronize351.584592415"
1782833568.115 sable_ircd     #x1B[2m2026-06-30T15:32:48.114545Z#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 :synchronize351.584592415
1782833568.115 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize351.584592415
1782833568.115 2 -> S: PRIVMSG bar :what's up
1782833568.116 sable_ircd     #x1B[2m2026-06-30T15:32:48.115511Z#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
1782833568.116 sable_ircd     #x1B[2m2026-06-30T15:32:48.115565Z#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"PRIVMSG bar :what's up"
1782833568.116 sable_ircd     #x1B[2m2026-06-30T15:32:48.115892Z#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(859818764491821063)), timestamp: 1782833568, clock: EventClock(1 => 859818760909885446), target: Message(MessageId(Uuid7(019f1929-5973-7460-94a1-1ea62e76fc16))), details: NewMessage(NewMessage { source: UserId(Snowflake(859818752470945794)), target: User(UserId(Snowflake(859818740856918016))), message_type: Privmsg, text: "what's up" }) }; event clock=EventClock(1 => 859818760909885446) my clock=EventClock(1 => 859818760909885446)
1782833568.116 sable_ircd     #x1B[2m2026-06-30T15:32:48.116046Z#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
1782833568.116 sable_ircd     #x1B[2m2026-06-30T15:32:48.116054Z#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
1782833568.116 sable_ircd     #x1B[2m2026-06-30T15:32:48.116303Z#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(859818764491821063)), timestamp: 1782833568, change: NewMessage(NewMessage { message: MessageId(Uuid7(019f1929-5973-7460-94a1-1ea62e76fc16)), source: User(HistoricUserId(UserId(Snowflake(859818752470945794)), 2)), target: User(HistoricUserId(UserId(Snowflake(859818740856918016)), 4)) }), users_to_notify: [UserId(Snowflake(859818740856918016)), UserId(Snowflake(859818752470945794))] })
1782833568.116 sable_ircd     #x1B[2m2026-06-30T15:32:48.116349Z#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(859818764491821063)), timestamp: 1782833568, change: NewMessage(NewMessage { message: MessageId(Uuid7(019f1929-5973-7460-94a1-1ea62e76fc16)), source: User(HistoricUserId(UserId(Snowflake(859818752470945794)), 2)), target: User(HistoricUserId(UserId(Snowflake(859818740856918016)), 4)) }), users_to_notify: [UserId(Snowflake(859818740856918016)), UserId(Snowflake(859818752470945794))] }
1782833568.117 sable_ircd     #x1B[2m2026-06-30T15:32:48.116471Z#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): :qux!username@localhost PRIVMSG bar :what's up
1782833568.117 sable_ircd     #x1B[2m2026-06-30T15:32:48.116566Z#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(859818764491821063)), timestamp: 1782833568, change: EventComplete(EventComplete), users_to_notify: [] })
1782833568.117 sable_ircd     #x1B[2m2026-06-30T15:32:48.116603Z#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(859818764491821063)), timestamp: 1782833568, change: EventComplete(EventComplete), users_to_notify: [] }
1782833568.215 2 -> S: PING synchronize352.438905938
1782833568.216 sable_ircd     #x1B[2m2026-06-30T15:32:48.215833Z#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
1782833568.216 sable_ircd     #x1B[2m2026-06-30T15:32:48.215892Z#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 synchronize352.438905938"
1782833569.216 2: waiting…
1782833569.221 sable_ircd     #x1B[2m2026-06-30T15:32:49.220494Z#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 :synchronize352.438905938
1782833569.221 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize352.438905938
1782833569.226 1: disconnects from server.
1782833569.226 2: disconnects from server.