--------------------------------- Captured Out --------------------------------- ---- new test ---- 1775415353.101 sable_ircd uid=1001 1775415353.101 sable_services uid=1001 1775415353.102 sable_services #x1B[2m2026-04-05T18:55:53.101776Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.Server"), address: "127.0.0.1:36691", fingerprint: "c05677d1da686b9aae66c27a1598b0f98d23ea50" } 1775415353.102 sable_services #x1B[2m2026-04-05T18:55:53.102017Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Could not connect to 127.0.0.1:36691. Last error: I/O error: Connection refused (os error 111) 1775415353.102 sable_services #x1B[2m2026-04-05T18:55:53.102066Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mI/O error: Connection refused (os error 111) 1775415353.105 sable_ircd #x1B[2m2026-04-05T18:55:53.104455Z#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(828704515598323712)), timestamp: 1775415353, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Server"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock() 1775415353.105 sable_ircd #x1B[2m2026-04-05T18:55:53.104817Z#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 1775415353.105 sable_ircd #x1B[2m2026-04-05T18:55:53.105059Z#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(828704515598323712)), timestamp: 1775415353, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Server"), ts: 1775415353, 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 1775415353.105 sable_ircd #x1B[2m2026-04-05T18:55:53.105161Z#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(828704515598323712)), timestamp: 1775415353, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }) 1775415353.105 sable_ircd #x1B[2m2026-04-05T18:55:53.105200Z#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(828704515598323712)), timestamp: 1775415353, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] } 1775415353.105 sable_ircd #x1B[2m2026-04-05T18:55:53.105261Z#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(828704515598323712)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415353.105 sable_ircd #x1B[2m2026-04-05T18:55:53.105293Z#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(828704515598323712)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] } 1775415353.106 sable_ircd #x1B[2m2026-04-05T18:55:53.105888Z#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 1775415353.106 sable_ircd #x1B[2m2026-04-05T18:55:53.105993Z#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(828704515602518017)), timestamp: 1775415353, clock: EventClock(1 => 828704515598323712), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1775415353 }) }; event clock=EventClock(1 => 828704515598323712) my clock=EventClock(1 => 828704515598323712) 1775415353.106 sable_ircd #x1B[2m2026-04-05T18:55:53.106070Z#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 1775415353.106 sable_ircd #x1B[2m2026-04-05T18:55:53.106294Z#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(828704515602518017)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415353.106 sable_ircd #x1B[2m2026-04-05T18:55:53.106324Z#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(828704515602518017)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] } 1775415353.177 sable_services #x1B[2m2026-04-05T18:55:53.176597Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.History"), address: "127.0.0.1:45363", fingerprint: "c375e6e71c13443684b1ae2377c9d35ee8d6ae1c" } 1775415353.177 sable_services #x1B[2m2026-04-05T18:55:53.176806Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Could not connect to 127.0.0.1:45363. Last error: I/O error: Connection refused (os error 111) 1775415353.177 sable_services #x1B[2m2026-04-05T18:55:53.176848Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mI/O error: Connection refused (os error 111) 1775415353.197 sable_ircd #x1B[2m2026-04-05T18:55:53.196964Z#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 } 1775415353.197 sable_ircd #x1B[2m2026-04-05T18:55:53.197092Z#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 1775415353.197 sable_ircd #x1B[2m2026-04-05T18:55:53.197132Z#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 1775415353.197 sable_ircd #x1B[2m2026-04-05T18:55:53.197189Z#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 1775415353.197 sable_ircd #x1B[2m2026-04-05T18:55:53.197303Z#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 1775415353.198 sable_ircd #x1B[2m2026-04-05T18:55:53.197365Z#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" 1775415353.198 sable_ircd #x1B[2m2026-04-05T18:55:53.197479Z#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 1775415353.198 sable_ircd #x1B[2m2026-04-05T18:55:53.197576Z#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)) 1775415353.198 sable_ircd #x1B[2m2026-04-05T18:55:53.197614Z#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) 1775415353.198 sable_ircd #x1B[2m2026-04-05T18:55:53.198103Z#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 1775415353.198 sable_ircd #x1B[2m2026-04-05T18:55:53.198194Z#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 1775415353.198 sable_ircd #x1B[2m2026-04-05T18:55:53.198395Z#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 1775415353.218 chkSvs: connects to server. 1775415353.218 chkSvs -> S: NICK chkSvs 1775415353.218 chkSvs -> S: USER chk chk chk chk 1775415353.218 sable_ircd #x1B[2m2026-04-05T18:55:53.218294Z#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 } 1775415353.218 sable_ircd #x1B[2m2026-04-05T18:55:53.218434Z#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 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.218492Z#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 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.218559Z#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 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.218713Z#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 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.218768Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK chkSvs" 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.218922Z#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: 593, tv_nsec: 365677063 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.218980Z#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 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.219008Z#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 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.219036Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER chk chk chk chk" 1775415353.219 sable_ircd #x1B[2m2026-04-05T18:55:53.219100Z#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: 593, tv_nsec: 365677063 }, attach_user_id: OnceLock(), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.219488Z#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 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.219526Z#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")) 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.219555Z#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 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.219577Z#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: 593, tv_nsec: 365677063 }, attach_user_id: OnceLock(), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.219616Z#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)) 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.219851Z#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(828704516080668674)), timestamp: 1775415353, clock: EventClock(1 => 828704515602518017), target: User(UserId(Snowflake(828704516080668672))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704516080668673)), NewUserConnection { user: UserId(Snowflake(828704516080668672)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415353 })) }) }; event clock=EventClock(1 => 828704515602518017) my clock=EventClock(1 => 828704515602518017) 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.219968Z#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 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220219Z#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(828704516080668674)), timestamp: 1775415353, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(828704516080668672)), 1) }), users_to_notify: [] }) 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220257Z#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(828704516080668674)), timestamp: 1775415353, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(828704516080668672)), 1) }), users_to_notify: [] } 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220311Z#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(828704516080668674)), timestamp: 1775415353, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(828704516080668672)), 2), connection: UserConnectionId(Snowflake(828704516080668673)) }), users_to_notify: [] }) 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220336Z#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(828704516080668674)), timestamp: 1775415353, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(828704516080668672)), 2), connection: UserConnectionId(Snowflake(828704516080668673)) }), users_to_notify: [] } 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220381Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220407Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220428Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 003 chkSvs :This server was created 2026-04-05 18:55:53.220421263 UTC 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220445Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1775415353.220 sable_ircd #x1B[2m2026-04-05T18:55:53.220479Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 005 chkSvs EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1775415353.221 sable_ircd #x1B[2m2026-04-05T18:55:53.220500Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 422 chkSvs :MOTD File is missing 1775415353.221 sable_ircd #x1B[2m2026-04-05T18:55:53.220522Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :chkSvs!chk@localhost MODE chkSvs + 1775415353.221 sable_ircd #x1B[2m2026-04-05T18:55:53.220538Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1775415353.221 sable_ircd #x1B[2m2026-04-05T18:55:53.220581Z#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(828704516080668674)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415353.221 sable_ircd #x1B[2m2026-04-05T18:55:53.220604Z#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(828704516080668674)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] } 1775415353.318 S -> chkSvs: :My.Little.Server NOTICE * :*** Looking up your hostname 1775415353.318 S -> chkSvs: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1775415353.318 S -> chkSvs: :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs 1775415353.318 S -> chkSvs: :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1775415353.318 S -> chkSvs: :My.Little.Server 003 chkSvs :This server was created 2026-04-05 18:55:53.220421263 UTC 1775415353.319 S -> chkSvs: :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1775415353.319 S -> chkSvs: :My.Little.Server 005 chkSvs EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1775415353.319 S -> chkSvs: :My.Little.Server 422 chkSvs :MOTD File is missing 1775415353.319 S -> chkSvs: :chkSvs!chk@localhost MODE chkSvs + 1775415353.319 S -> chkSvs: :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1775415353.319 chkSvs -> S: CAP LS 302 1775415353.319 sable_ircd #x1B[2m2026-04-05T18:55:53.318948Z#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 1775415353.319 sable_ircd #x1B[2m2026-04-05T18:55:53.319008Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP LS 302" 1775415353.319 sable_ircd #x1B[2m2026-04-05T18:55:53.319125Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server CAP * LS :message-tags server-time echo-message batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1775415353.352 sable_services #x1B[2m2026-04-05T18:55:53.352409Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.Server"), address: "127.0.0.1:36691", fingerprint: "c05677d1da686b9aae66c27a1598b0f98d23ea50" } (attempt #3). 1775415353.353 sable_services #x1B[2m2026-04-05T18:55:53.352461Z#x1B[0m #x1B[33m WARN#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Make sure at least one node in your network is started and reachable. If this is the first (or only) node, you must provide the --bootstrap-network option. 1775415353.353 sable_services #x1B[2m2026-04-05T18:55:53.352713Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415353.357 sable_ircd #x1B[2m2026-04-05T18:55:53.357214Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::node#x1B[0m#x1B[2m:#x1B[0m Server got state export request; sending 1775415353.369 chkSvs -> S: PING synchronize593.515834596 1775415353.369 S -> chkSvs: :My.Little.Server CAP * LS :message-tags server-time echo-message batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1775415353.369 sable_ircd #x1B[2m2026-04-05T18:55:53.369071Z#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 1775415353.369 sable_ircd #x1B[2m2026-04-05T18:55:53.369125Z#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 synchronize593.515834596" 1775415353.369 sable_ircd #x1B[2m2026-04-05T18:55:53.369233Z#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 :synchronize593.515834596 1775415353.370 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize593.515834596 1775415353.370 chkSvs -> S: CAP LS 302 1775415353.370 sable_ircd #x1B[2m2026-04-05T18:55:53.369956Z#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 1775415353.370 sable_ircd #x1B[2m2026-04-05T18:55:53.369997Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP LS 302" 1775415353.399 sable_services #x1B[2m2026-04-05T18:55:53.398517Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Bootstrap message: Message { source_server: (ServerId(1), 1775415353), content: NetworkState(Network { nick_bindings: {Nickname("chkSvs"): NickBinding { nick: Nickname("chkSvs"), user: UserId(Snowflake(828704516080668672)), timestamp: 1775415353, created: EventId(Snowflake(828704516080668674)) }}, historic_nick_users: HistoricNickStore { data: {} }, users: {UserId(Snowflake(828704516080668672)): User { id: UserId(Snowflake(828704516080668672)), serial: 2, user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, mode: UserMode { modes: UserModeSet(0) }, oper_privileges: None, account: None, session_key: None }}, historic_users: HistoricUserStore { users: {HistoricUserId(UserId(Snowflake(828704516080668672)), 1): HistoricUser { id: UserId(Snowflake(828704516080668672)), serial: 1, nickname: Nickname("chkSvs"), user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, account: None, timestamp: Some(1775415353) }, HistoricUserId(UserId(Snowflake(828704516080668672)), 2): HistoricUser { id: UserId(Snowflake(828704516080668672)), serial: 2, nickname: Nickname("chkSvs"), user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, account: None, timestamp: None }} }, user_connections: {UserConnectionId(Snowflake(828704516080668673)): UserConnection { id: UserConnectionId(Snowflake(828704516080668673)), user: UserId(Snowflake(828704516080668672)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415353 }}, channels: {}, list_mode_entries: {}, channel_topics: {}, channel_invites: {}, memberships: {}, messages: {}, servers: {ServerId(1): Server { id: ServerId(1), epoch: 1775415353, name: ServerName("My.Little.Server"), last_ping: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }}, network_bans: BanRepository { pre_registration_bans: {}, new_connection_bans: {}, pre_sasl_bans: {}, pre_registration_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: ["", "", "", "", "", ""], uint64: [], regex: [] }, fields: {"realname": (5, String(_)), "user_param_1": (0, String(_)), "nick": (1, String(_)), "user_param_2": (3, String(_)), "ip": (0, Ip(_)), "user": (2, String(_)), "tls": (0, Boolean(_)), "host": (4, String(_))} }, new_connection_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [], uint64: [], regex: [] }, fields: {"ip": (0, Ip(_)), "tls": (0, Boolean(_))} }, pre_sasl_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [""], uint64: [], regex: [] }, fields: {"mechanism": (0, String(_)), "ip": (0, Ip(_)), "tls": (0, Boolean(_))} } }, audit_log: {}, accounts: {}, nick_registrations: {}, channel_registrations: {}, channel_accesses: {}, channel_roles: {}, current_services: None, current_history_server_id: None, config: NetworkConfig { opers: [OperConfig { name: "operuser", hash: "$6$z5yA.OfGliDoi/R2$BgSsguS6bxAsPSCygDisgDw5JZuo5.88eU3Hyc7/4OaNpeKIxWGjOggeHzOl0xLiZg1vfwxXjOTFN14wG5vNI." }], debug_mode: true, default_roles: {BuiltinVoice: ChannelAccessSet(4296081441), BuiltinOp: ChannelAccessSet(222192934775), BuiltinAll: ChannelAccessSet(1114112)}, alias_users: [AliasUser { nick: Nickname("ChanServ"), user: Username("ChanServ"), host: Hostname("services."), realname: Realname("Channel services compatibility layer"), command_alias: "CS" }, AliasUser { nick: Nickname("NickServ"), user: Username("NickServ"), host: Hostname("services."), realname: Realname("Account services compatibility layer"), command_alias: "NS" }], object_expiry: 60, pingout_duration: 600 }, clock: EventClock(1 => 828704516080668674), cache_default_channel_roles: OnceLock(), alias_users: OnceLock() }) } 1775415353.399 sable_services #x1B[2m2026-04-05T18:55:53.398667Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Server 1775415353.399 sable_services #x1B[2m2026-04-05T18:55:53.398760Z#x1B[0m #x1B[33m WARN#x1B[0m #x1B[2msable_services::database::jsonfile#x1B[0m#x1B[2m:#x1B[0m Couldn't open database file, starting from empty 1775415353.399 sable_services #x1B[2m2026-04-05T18:55:53.399291Z#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(828704516836044800)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Services"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 828704516080668674) my clock=EventClock(1 => 828704516080668674) 1775415353.400 sable_services #x1B[2m2026-04-05T18:55:53.399536Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516836044800)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Services"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415353.401 sable_services #x1B[2m2026-04-05T18:55:53.401311Z#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(828704516844433409)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516836044800), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1775415353 }) }; event clock=EventClock(1 => 828704516080668674, 99 => 828704516836044800) my clock=EventClock(1 => 828704516080668674, 99 => 828704516836044800) 1775415353.402 sable_services #x1B[2m2026-04-05T18:55:53.401987Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516844433409)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516836044800), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1775415353 }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415353.406 sable_services #x1B[2m2026-04-05T18:55:53.405567Z#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(828704516836044800)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Services"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(99) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Services 1775415353.406 sable_services #x1B[2m2026-04-05T18:55:53.405793Z#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(828704516861210626)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516844433409), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 828704516080668674, 99 => 828704516844433409) my clock=EventClock(1 => 828704516080668674, 99 => 828704516844433409) 1775415353.406 sable_services #x1B[2m2026-04-05T18:55:53.406050Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516861210626)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516844433409), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415353.407 sable_ircd #x1B[2m2026-04-05T18:55:53.406491Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516844433409)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516836044800), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1775415353 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(828704516844433409)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516836044800), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1775415353 }) }; event clock=EventClock(1 => 828704516080668674, 99 => 828704516836044800) my clock=EventClock(1 => 828704516080668674) 1775415353.407 sable_ircd #x1B[2m2026-04-05T18:55:53.406586Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516844433409)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516836044800), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1775415353 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(828704516836044800))] 1775415353.407 sable_ircd #x1B[2m2026-04-05T18:55:53.406639Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516844433409)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516836044800), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1775415353 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1775415353.420 chkSvs -> S: PING synchronize593.566886577 1775415353.420 sable_ircd #x1B[2m2026-04-05T18:55:53.420072Z#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 1775415353.420 sable_ircd #x1B[2m2026-04-05T18:55:53.420123Z#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 synchronize593.566886577" 1775415353.442 sable_ircd #x1B[2m2026-04-05T18:55:53.442176Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516836044800)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Services"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(828704516836044800)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Services"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 828704516080668674) my clock=EventClock(1 => 828704516080668674) 1775415353.442 sable_ircd #x1B[2m2026-04-05T18:55:53.442265Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516836044800)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Services"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(828704516844433409)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516836044800), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1775415353 }) } 1775415353.442 sable_ircd #x1B[2m2026-04-05T18:55:53.442353Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516836044800)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Services"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1775415353.443 sable_ircd #x1B[2m2026-04-05T18:55:53.442615Z#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(828704516836044800)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1775415353, name: ServerName("My.Little.Services"), ts: 1775415353, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(99) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Services 1775415353.443 sable_ircd #x1B[2m2026-04-05T18:55:53.442819Z#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(828704516836044800)), timestamp: 1775415353, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] }) 1775415353.443 sable_ircd #x1B[2m2026-04-05T18:55:53.442851Z#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(828704516836044800)), timestamp: 1775415353, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] } 1775415353.443 sable_ircd #x1B[2m2026-04-05T18:55:53.442934Z#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(828704516836044800)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415353.443 sable_ircd #x1B[2m2026-04-05T18:55:53.442963Z#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(828704516836044800)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] } 1775415353.443 sable_ircd #x1B[2m2026-04-05T18:55:53.442999Z#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(828704516844433409)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415353.443 sable_ircd #x1B[2m2026-04-05T18:55:53.443019Z#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(828704516844433409)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] } 1775415353.447 sable_services #x1B[2m2026-04-05T18:55:53.447059Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: GetEvent([EventId(Snowflake(828704516836044800))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(828704516836044800))] 1775415353.447 sable_services #x1B[2m2026-04-05T18:55:53.447104Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: GetEvent([EventId(Snowflake(828704516836044800))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [] 1775415353.449 sable_ircd #x1B[2m2026-04-05T18:55:53.449149Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516861210626)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516844433409), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(828704516861210626)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516844433409), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 828704516080668674, 99 => 828704516844433409) my clock=EventClock(1 => 828704516080668674, 99 => 828704516844433409) 1775415353.450 sable_ircd #x1B[2m2026-04-05T18:55:53.449451Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516861210626)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516844433409), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704516861210626)), timestamp: 1775415353, clock: EventClock(1 => 828704516080668674, 99 => 828704516844433409), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:45445 (127.0.0.1:45445) 1775415353.453 sable_ircd #x1B[2m2026-04-05T18:55:53.453312Z#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(828704516861210626)), timestamp: 1775415353, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] }) 1775415353.453 sable_ircd #x1B[2m2026-04-05T18:55:53.453394Z#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(828704516861210626)), timestamp: 1775415353, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] } 1775415353.454 sable_ircd #x1B[2m2026-04-05T18:55:53.453505Z#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(828704516861210626)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415353.454 sable_ircd #x1B[2m2026-04-05T18:55:53.453555Z#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(828704516861210626)), timestamp: 1775415353, change: EventComplete(EventComplete), users_to_notify: [] } 1775415353.488 sable_ircd #x1B[2m2026-04-05T18:55:53.488207Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: BulkEvents([]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [] 1775415354.207 sable_ircd #x1B[2m2026-04-05T18:55:54.207189Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1775415354.208 S -> chkSvs: :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1775415355.209 chkSvs: waiting… 1775415355.213 sable_ircd #x1B[2m2026-04-05T18:55:55.212839Z#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 :synchronize593.566886577 1775415355.214 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize593.566886577 1775415355.214 chkSvs -> S: QUIT 1775415355.214 chkSvs -> S: PING synchronize595.361152888 1775415355.215 sable_ircd #x1B[2m2026-04-05T18:55:55.214712Z#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 1775415355.215 sable_ircd #x1B[2m2026-04-05T18:55:55.214771Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"QUIT" 1775415355.215 sable_ircd #x1B[2m2026-04-05T18:55:55.214830Z#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 1775415355.215 sable_ircd #x1B[2m2026-04-05T18:55:55.214994Z#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 synchronize595.361152888" 1775415356.215 chkSvs: waiting… 1775415356.219 sable_ircd #x1B[2m2026-04-05T18:55:56.219181Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): ERROR :Client quit 1775415356.219 sable_ircd #x1B[2m2026-04-05T18:55:56.219340Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mCloseConnection(ConnectionId(ListenerId(0), 2)) 1775415356.219 sable_ircd #x1B[2m2026-04-05T18:55:56.219402Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 2) 1775415356.220 S -> chkSvs: ERROR :Client quit 1775415356.220 chkSvs: disconnects from server. 1775415356.220 1: connects to server. 1775415356.220 1 -> S: NICK coolNick 1775415356.220 1 -> S: USER myusernam 0 * :My UniqueReal Name 1775415356.220 sable_ircd #x1B[2m2026-04-05T18:55:56.219563Z#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(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 828704516080668674, 99 => 828704516861210626) my clock=EventClock(1 => 828704516080668674, 99 => 828704516861210626) 1775415356.220 sable_ircd #x1B[2m2026-04-05T18:55:56.219925Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m Connection error #x1B[3mconnection#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed 1775415356.220 sable_ircd #x1B[2m2026-04-05T18:55:56.220066Z#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 1775415356.220 sable_ircd #x1B[2m2026-04-05T18:55:56.220072Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:45445 (127.0.0.1:45445) 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.220522Z#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 } 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.220615Z#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 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.220672Z#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 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.220733Z#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 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.220895Z#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 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.220950Z#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 coolNick" 1775415356.221 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.221054Z#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 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.221104Z#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 myusernam 0 * :My UniqueReal Name" 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.221178Z#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: 596, tv_nsec: 367852214 }, attach_user_id: OnceLock(), user: OnceLock(Username("myusernam")), extra_user_params: OnceLock(("0", "*")), nick: OnceLock(), realname: OnceLock(Realname("My UniqueReal Name")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.221245Z#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: 596, tv_nsec: 367852214 }, attach_user_id: OnceLock(), user: OnceLock(Username("myusernam")), extra_user_params: OnceLock(("0", "*")), nick: OnceLock(Nickname("coolNick")), realname: OnceLock(Realname("My UniqueReal Name")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415356.221 sable_ircd #x1B[2m2026-04-05T18:55:56.221281Z#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 1775415356.222 sable_ircd #x1B[2m2026-04-05T18:55:56.222279Z#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(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 828704528663580675, 99 => 828704516861210626) my clock=EventClock(1 => 828704528663580675, 99 => 828704516861210626) 1775415356.223 sable_ircd #x1B[2m2026-04-05T18:55:56.222786Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:45445 (127.0.0.1:45445) 1775415356.223 sable_ircd #x1B[2m2026-04-05T18:55:56.222934Z#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 1775415356.223 sable_ircd #x1B[2m2026-04-05T18:55:56.222977Z#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")) 1775415356.223 sable_ircd #x1B[2m2026-04-05T18:55:56.223021Z#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 1775415356.223 sable_ircd #x1B[2m2026-04-05T18:55:56.223059Z#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: 596, tv_nsec: 367852214 }, attach_user_id: OnceLock(), user: OnceLock(Username("myusernam")), extra_user_params: OnceLock(("0", "*")), nick: OnceLock(Nickname("coolNick")), realname: OnceLock(Realname("My UniqueReal Name")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415356.223 sable_ircd #x1B[2m2026-04-05T18:55:56.223121Z#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)) 1775415356.223 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1775415356.226 sable_ircd #x1B[2m2026-04-05T18:55:56.226396Z#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(828704528692940805)), timestamp: 1775415356, clock: EventClock(1 => 828704528676163588, 99 => 828704516861210626), target: User(UserId(Snowflake(828704528680357890))), details: NewUser(NewUser { nickname: Nickname("coolNick"), username: Username("myusernam"), visible_hostname: Hostname("localhost"), realname: Realname("My UniqueReal Name"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704528680357891)), NewUserConnection { user: UserId(Snowflake(828704528680357890)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415356 })) }) }; event clock=EventClock(1 => 828704528676163588, 99 => 828704516861210626) my clock=EventClock(1 => 828704528676163588, 99 => 828704516861210626) 1775415356.227 sable_ircd #x1B[2m2026-04-05T18:55:56.227115Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528692940805)), timestamp: 1775415356, clock: EventClock(1 => 828704528676163588, 99 => 828704516861210626), target: User(UserId(Snowflake(828704528680357890))), details: NewUser(NewUser { nickname: Nickname("coolNick"), username: Username("myusernam"), visible_hostname: Hostname("localhost"), realname: Realname("My UniqueReal Name"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704528680357891)), NewUserConnection { user: UserId(Snowflake(828704528680357890)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415356 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:45445 (127.0.0.1:45445) 1775415356.228 sable_services #x1B[2m2026-04-05T18:55:56.227436Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 828704528663580675, 99 => 828704516861210626) my clock=EventClock(1 => 828704516080668674, 99 => 828704516861210626) 1775415356.228 sable_services #x1B[2m2026-04-05T18:55:56.227523Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(828704528663580675))] 1775415356.228 sable_services #x1B[2m2026-04-05T18:55:56.227937Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415356.231 sable_ircd #x1B[2m2026-04-05T18:55:56.230494Z#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(828704528663580675)), timestamp: 1775415356, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(828704516080668672)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(828704516080668673)), user: UserId(Snowflake(828704516080668672)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415353 } }), users_to_notify: [] }) 1775415356.231 sable_ircd #x1B[2m2026-04-05T18:55:56.230609Z#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(828704528663580675)), timestamp: 1775415356, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(828704516080668672)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(828704516080668673)), user: UserId(Snowflake(828704516080668672)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415353 } }), users_to_notify: [] } 1775415356.231 sable_ircd #x1B[2m2026-04-05T18:55:56.230906Z#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(828704528663580675)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415356.231 sable_ircd #x1B[2m2026-04-05T18:55:56.230980Z#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(828704528663580675)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] } 1775415356.231 sable_ircd #x1B[2m2026-04-05T18:55:56.231162Z#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(828704528676163588)), timestamp: 1775415356, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(828704516080668672)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] }) 1775415356.231 sable_ircd #x1B[2m2026-04-05T18:55:56.231237Z#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(828704528676163588)), timestamp: 1775415356, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(828704516080668672)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] } 1775415356.231 sable_ircd #x1B[2m2026-04-05T18:55:56.231350Z#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(828704528676163588)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231483Z#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(828704528676163588)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] } 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231584Z#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(828704528692940805)), timestamp: 1775415356, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(828704528680357890)), 1) }), users_to_notify: [] }) 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231654Z#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(828704528692940805)), timestamp: 1775415356, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(828704528680357890)), 1) }), users_to_notify: [] } 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231757Z#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(828704528692940805)), timestamp: 1775415356, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(828704528680357890)), 2), connection: UserConnectionId(Snowflake(828704528680357891)) }), users_to_notify: [] }) 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231803Z#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(828704528692940805)), timestamp: 1775415356, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(828704528680357890)), 2), connection: UserConnectionId(Snowflake(828704528680357891)) }), users_to_notify: [] } 1775415356.232 sable_services #x1B[2m2026-04-05T18:55:56.231159Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528692940805)), timestamp: 1775415356, clock: EventClock(1 => 828704528676163588, 99 => 828704516861210626), target: User(UserId(Snowflake(828704528680357890))), details: NewUser(NewUser { nickname: Nickname("coolNick"), username: Username("myusernam"), visible_hostname: Hostname("localhost"), realname: Realname("My UniqueReal Name"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704528680357891)), NewUserConnection { user: UserId(Snowflake(828704528680357890)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415356 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(828704528692940805)), timestamp: 1775415356, clock: EventClock(1 => 828704528676163588, 99 => 828704516861210626), target: User(UserId(Snowflake(828704528680357890))), details: NewUser(NewUser { nickname: Nickname("coolNick"), username: Username("myusernam"), visible_hostname: Hostname("localhost"), realname: Realname("My UniqueReal Name"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704528680357891)), NewUserConnection { user: UserId(Snowflake(828704528680357890)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415356 })) }) }; event clock=EventClock(1 => 828704528676163588, 99 => 828704516861210626) my clock=EventClock(1 => 828704516080668674, 99 => 828704516861210626) 1775415356.232 sable_services #x1B[2m2026-04-05T18:55:56.231226Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528692940805)), timestamp: 1775415356, clock: EventClock(1 => 828704528676163588, 99 => 828704516861210626), target: User(UserId(Snowflake(828704528680357890))), details: NewUser(NewUser { nickname: Nickname("coolNick"), username: Username("myusernam"), visible_hostname: Hostname("localhost"), realname: Realname("My UniqueReal Name"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704528680357891)), NewUserConnection { user: UserId(Snowflake(828704528680357890)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415356 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(828704528676163588))] 1775415356.232 sable_services #x1B[2m2026-04-05T18:55:56.231747Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528692940805)), timestamp: 1775415356, clock: EventClock(1 => 828704528676163588, 99 => 828704516861210626), target: User(UserId(Snowflake(828704528680357890))), details: NewUser(NewUser { nickname: Nickname("coolNick"), username: Username("myusernam"), visible_hostname: Hostname("localhost"), realname: Realname("My UniqueReal Name"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704528680357891)), NewUserConnection { user: UserId(Snowflake(828704528680357890)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415356 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528692940805)), timestamp: 1775415356, clock: EventClock(1 => 828704528676163588, 99 => 828704516861210626), target: User(UserId(Snowflake(828704528680357890))), details: NewUser(NewUser { nickname: Nickname("coolNick"), username: Username("myusernam"), visible_hostname: Hostname("localhost"), realname: Realname("My UniqueReal Name"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704528680357891)), NewUserConnection { user: UserId(Snowflake(828704528680357890)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415356 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231893Z#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 coolNick :Welcome to the test Internet Relay Chat network, coolNick 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231944Z#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 coolNick :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231968Z#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 coolNick :This server was created 2026-04-05 18:55:56.231960733 UTC 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.231982Z#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 coolNick My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.232000Z#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 coolNick 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 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.232014Z#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 coolNick :MOTD File is missing 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.232028Z#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): :coolNick!myusernam@localhost MODE coolNick + 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.232040Z#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 coolNick :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.232074Z#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(828704528692940805)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415356.232 sable_ircd #x1B[2m2026-04-05T18:55:56.232089Z#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(828704528692940805)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] } 1775415356.232 S -> 1: :My.Little.Server 001 coolNick :Welcome to the test Internet Relay Chat network, coolNick 1775415356.233 S -> 1: :My.Little.Server 002 coolNick :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1775415356.233 S -> 1: :My.Little.Server 003 coolNick :This server was created 2026-04-05 18:55:56.231960733 UTC 1775415356.233 S -> 1: :My.Little.Server 004 coolNick My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1775415356.233 S -> 1: :My.Little.Server 005 coolNick 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 1775415356.233 S -> 1: :My.Little.Server 422 coolNick :MOTD File is missing 1775415356.233 S -> 1: :coolNick!myusernam@localhost MODE coolNick + 1775415356.233 S -> 1: :My.Little.Server NOTICE coolNick :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1775415356.263 sable_services #x1B[2m2026-04-05T18:55:56.263209Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 828704516080668674, 99 => 828704516861210626) my clock=EventClock(1 => 828704516080668674, 99 => 828704516861210626) 1775415356.263 sable_services #x1B[2m2026-04-05T18:55:56.263298Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) } 1775415356.263 sable_services #x1B[2m2026-04-05T18:55:56.263344Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(828704528692940805)), timestamp: 1775415356, clock: EventClock(1 => 828704528676163588, 99 => 828704516861210626), target: User(UserId(Snowflake(828704528680357890))), details: NewUser(NewUser { nickname: Nickname("coolNick"), username: Username("myusernam"), visible_hostname: Hostname("localhost"), realname: Realname("My UniqueReal Name"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(828704528680357891)), NewUserConnection { user: UserId(Snowflake(828704528680357890)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415356 })) }) } 1775415356.264 sable_services #x1B[2m2026-04-05T18:55:56.263665Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415356.268 sable_ircd #x1B[2m2026-04-05T18:55:56.268076Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: GetEvent([EventId(Snowflake(828704528663580675))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(828704528663580675))] 1775415356.268 sable_ircd #x1B[2m2026-04-05T18:55:56.268124Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: GetEvent([EventId(Snowflake(828704528663580675))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }] 1775415356.272 sable_ircd #x1B[2m2026-04-05T18:55:56.272109Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: GetEvent([EventId(Snowflake(828704528676163588))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(828704528676163588))] 1775415356.272 sable_ircd #x1B[2m2026-04-05T18:55:56.272152Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: GetEvent([EventId(Snowflake(828704528676163588))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }] 1775415356.309 sable_services #x1B[2m2026-04-05T18:55:56.309054Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: BulkEvents([Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(828704528663580675)), timestamp: 1775415356, clock: EventClock(1 => 828704516080668674, 99 => 828704516861210626), target: UserConnection(UserConnectionId(Snowflake(828704516080668673))), details: UserDisconnect(UserDisconnect) }] 1775415356.314 sable_services #x1B[2m2026-04-05T18:55:56.314070Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: BulkEvents([Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(828704528676163588)), timestamp: 1775415356, clock: EventClock(1 => 828704528663580675, 99 => 828704516861210626), target: User(UserId(Snowflake(828704516080668672))), details: UserQuit(UserQuit { message: "Client Quit" }) }] 1775415356.333 1 -> S: PING synchronize596.479848024 1775415356.333 sable_ircd #x1B[2m2026-04-05T18:55:56.333191Z#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 1775415356.333 sable_ircd #x1B[2m2026-04-05T18:55:56.333240Z#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 synchronize596.479848024" 1775415356.333 sable_ircd #x1B[2m2026-04-05T18:55:56.333351Z#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 :synchronize596.479848024 1775415356.334 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize596.479848024 1775415356.334 1 -> S: JOIN #chan 1775415356.334 sable_ircd #x1B[2m2026-04-05T18:55:56.333944Z#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 1775415356.334 sable_ircd #x1B[2m2026-04-05T18:55:56.334015Z#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 #chan" 1775415356.334 sable_ircd #x1B[2m2026-04-05T18:55:56.334205Z#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(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 828704528692940805, 99 => 828704516861210626) my clock=EventClock(1 => 828704528692940805, 99 => 828704516861210626) 1775415356.335 sable_ircd #x1B[2m2026-04-05T18:55:56.334568Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:45445 (127.0.0.1:45445) 1775415356.336 sable_ircd #x1B[2m2026-04-05T18:55:56.336243Z#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(828704529154314247)), timestamp: 1775415356, clock: EventClock(1 => 828704529145925638, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704528680357890)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 828704529145925638, 99 => 828704516861210626) my clock=EventClock(1 => 828704529145925638, 99 => 828704516861210626) 1775415356.337 sable_ircd #x1B[2m2026-04-05T18:55:56.336390Z#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 1775415356.337 sable_ircd #x1B[2m2026-04-05T18:55:56.336654Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529154314247)), timestamp: 1775415356, clock: EventClock(1 => 828704529145925638, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704528680357890)), permissions: MembershipFlagSet(1) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:45445 (127.0.0.1:45445) 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.340494Z#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(828704529145925638)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.340539Z#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(828704529145925638)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] } 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.340613Z#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(828704529154314247)), timestamp: 1775415356, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636))), user: HistoricUserId(UserId(Snowflake(828704528680357890)), 2) }), users_to_notify: [UserId(Snowflake(828704528680357890))] }) 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.340660Z#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(828704529154314247)), timestamp: 1775415356, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636))), user: HistoricUserId(UserId(Snowflake(828704528680357890)), 2) }), users_to_notify: [UserId(Snowflake(828704528680357890))] } 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.340733Z#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): :coolNick!myusernam@localhost JOIN #chan 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.340794Z#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): :coolNick!myusernam@localhost MODE #chan +o coolNick 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.340850Z#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 coolNick = #chan :@coolNick 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.340922Z#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 coolNick #chan :End of names list 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.341002Z#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(828704529154314247)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415356.341 sable_ircd #x1B[2m2026-04-05T18:55:56.341047Z#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(828704529154314247)), timestamp: 1775415356, change: EventComplete(EventComplete), users_to_notify: [] } 1775415356.341 sable_services #x1B[2m2026-04-05T18:55:56.341157Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529154314247)), timestamp: 1775415356, clock: EventClock(1 => 828704529145925638, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704528680357890)), permissions: MembershipFlagSet(1) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(828704529154314247)), timestamp: 1775415356, clock: EventClock(1 => 828704529145925638, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704528680357890)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 828704529145925638, 99 => 828704516861210626) my clock=EventClock(1 => 828704528692940805, 99 => 828704516861210626) 1775415356.341 sable_services #x1B[2m2026-04-05T18:55:56.341234Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529154314247)), timestamp: 1775415356, clock: EventClock(1 => 828704529145925638, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704528680357890)), permissions: MembershipFlagSet(1) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(828704529145925638))] 1775415356.342 sable_services #x1B[2m2026-04-05T18:55:56.341606Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529154314247)), timestamp: 1775415356, clock: EventClock(1 => 828704529145925638, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704528680357890)), permissions: MembershipFlagSet(1) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529154314247)), timestamp: 1775415356, clock: EventClock(1 => 828704529145925638, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704528680357890)), permissions: MembershipFlagSet(1) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415356.377 sable_services #x1B[2m2026-04-05T18:55:56.377165Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 828704528692940805, 99 => 828704516861210626) my clock=EventClock(1 => 828704528692940805, 99 => 828704516861210626) 1775415356.377 sable_services #x1B[2m2026-04-05T18:55:56.377255Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(828704529154314247)), timestamp: 1775415356, clock: EventClock(1 => 828704529145925638, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704528680357890)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704528680357890)), permissions: MembershipFlagSet(1) }) } 1775415356.378 sable_services #x1B[2m2026-04-05T18:55:56.377659Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415356.382 sable_ircd #x1B[2m2026-04-05T18:55:56.382090Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: GetEvent([EventId(Snowflake(828704529145925638))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(828704529145925638))] 1775415356.382 sable_ircd #x1B[2m2026-04-05T18:55:56.382138Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1775415353), content: GetEvent([EventId(Snowflake(828704529145925638))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }] 1775415356.423 sable_services #x1B[2m2026-04-05T18:55:56.423079Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: BulkEvents([Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(828704529145925638)), timestamp: 1775415356, clock: EventClock(1 => 828704528692940805, 99 => 828704516861210626), target: Channel(ChannelId(Snowflake(828704529145925636))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }] 1775415356.434 1 -> S: PING synchronize596.580952628 1775415356.434 S -> 1: :coolNick!myusernam@localhost JOIN #chan 1775415356.434 S -> 1: :coolNick!myusernam@localhost MODE #chan +o coolNick 1775415356.434 S -> 1: :My.Little.Server 353 coolNick = #chan :@coolNick 1775415356.434 S -> 1: :My.Little.Server 366 coolNick #chan :End of names list 1775415356.434 sable_ircd #x1B[2m2026-04-05T18:55:56.434118Z#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 1775415356.434 sable_ircd #x1B[2m2026-04-05T18:55:56.434160Z#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 synchronize596.580952628" 1775415357.188 sable_ircd #x1B[2m2026-04-05T18:55:57.187954Z#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 :synchronize596.580952628 1775415357.188 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize596.580952628 1775415357.189 2: connects to server. 1775415357.189 2 -> S: NICK otherNick 1775415357.189 2 -> S: USER username * * :Realname 1775415357.189 sable_ircd #x1B[2m2026-04-05T18:55:57.189210Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 4), remote_addr: 127.0.0.1, tls_info: None } 1775415357.189 sable_ircd #x1B[2m2026-04-05T18:55:57.189295Z#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 1775415357.189 sable_ircd #x1B[2m2026-04-05T18:55:57.189330Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1775415357.189 sable_ircd #x1B[2m2026-04-05T18:55:57.189378Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server NOTICE * :*** Looking up your hostname 1775415357.190 sable_ircd #x1B[2m2026-04-05T18:55:57.189505Z#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 1775415357.190 sable_ircd #x1B[2m2026-04-05T18:55:57.189558Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK otherNick" 1775415357.190 sable_ircd #x1B[2m2026-04-05T18:55:57.189662Z#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 1775415357.190 S -> 2: :My.Little.Server NOTICE * :*** Looking up your hostname 1775415357.190 sable_ircd #x1B[2m2026-04-05T18:55:57.189718Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER username * * :Realname" 1775415357.190 sable_ircd #x1B[2m2026-04-05T18:55:57.189806Z#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: 597, tv_nsec: 336501125 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(), realname: OnceLock(Realname("Realname")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415357.190 sable_ircd #x1B[2m2026-04-05T18:55:57.189916Z#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: 597, tv_nsec: 336501125 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("otherNick")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415357.190 sable_ircd #x1B[2m2026-04-05T18:55:57.189962Z#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 1775415357.191 sable_ircd #x1B[2m2026-04-05T18:55:57.190486Z#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 1775415357.191 sable_ircd #x1B[2m2026-04-05T18:55:57.190516Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m DNS lookup finished for ConnectionId(ListenerId(0), 4): 127.0.0.1/Some(Hostname("localhost")) 1775415357.191 sable_ircd #x1B[2m2026-04-05T18:55:57.190542Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server NOTICE * :*** Found your hostname: localhost 1775415357.191 sable_ircd #x1B[2m2026-04-05T18:55:57.190563Z#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: 597, tv_nsec: 336501125 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("otherNick")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1775415357.191 sable_ircd #x1B[2m2026-04-05T18:55:57.190604Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mRegisterClient(ConnectionId(ListenerId(0), 4)) 1775415357.191 sable_ircd #x1B[2m2026-04-05T18:55:57.190821Z#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(828704532736249864)), timestamp: 1775415357, clock: EventClock(1 => 828704529154314247, 99 => 828704516861210626), target: User(UserId(Snowflake(828704532736249861))), details: NewUser(NewUser { nickname: Nickname("otherNick"), 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(828704532736249862)), NewUserConnection { user: UserId(Snowflake(828704532736249861)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415357 })) }) }; event clock=EventClock(1 => 828704529154314247, 99 => 828704516861210626) my clock=EventClock(1 => 828704529154314247, 99 => 828704516861210626) 1775415357.191 S -> 2: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1775415357.191 sable_ircd #x1B[2m2026-04-05T18:55:57.191142Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704532736249864)), timestamp: 1775415357, clock: EventClock(1 => 828704529154314247, 99 => 828704516861210626), target: User(UserId(Snowflake(828704532736249861))), details: NewUser(NewUser { nickname: Nickname("otherNick"), 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(828704532736249862)), NewUserConnection { user: UserId(Snowflake(828704532736249861)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415357 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:45445 (127.0.0.1:45445) 1775415357.193 sable_ircd #x1B[2m2026-04-05T18:55:57.193227Z#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(828704532736249864)), timestamp: 1775415357, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(828704532736249861)), 1) }), users_to_notify: [] }) 1775415357.193 sable_ircd #x1B[2m2026-04-05T18:55:57.193275Z#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(828704532736249864)), timestamp: 1775415357, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(828704532736249861)), 1) }), users_to_notify: [] } 1775415357.193 sable_ircd #x1B[2m2026-04-05T18:55:57.193329Z#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(828704532736249864)), timestamp: 1775415357, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(828704532736249861)), 2), connection: UserConnectionId(Snowflake(828704532736249862)) }), users_to_notify: [] }) 1775415357.193 sable_ircd #x1B[2m2026-04-05T18:55:57.193353Z#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(828704532736249864)), timestamp: 1775415357, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(828704532736249861)), 2), connection: UserConnectionId(Snowflake(828704532736249862)) }), users_to_notify: [] } 1775415357.193 sable_ircd #x1B[2m2026-04-05T18:55:57.193395Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 001 otherNick :Welcome to the test Internet Relay Chat network, otherNick 1775415357.193 sable_ircd #x1B[2m2026-04-05T18:55:57.193426Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 002 otherNick :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.193467Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 003 otherNick :This server was created 2026-04-05 18:55:57.193452117 UTC 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.193507Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 004 otherNick My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.193547Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 005 otherNick 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 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.193567Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 422 otherNick :MOTD File is missing 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.193582Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :otherNick!username@localhost MODE otherNick + 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.193595Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server NOTICE otherNick :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.193654Z#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(828704532736249864)), timestamp: 1775415357, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.193690Z#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(828704532736249864)), timestamp: 1775415357, change: EventComplete(EventComplete), users_to_notify: [] } 1775415357.194 S -> 2: :My.Little.Server 001 otherNick :Welcome to the test Internet Relay Chat network, otherNick 1775415357.194 2 -> S: PING foo 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.194253Z#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 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.194291Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING foo" 1775415357.194 sable_ircd #x1B[2m2026-04-05T18:55:57.194357Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server PONG My.Little.Server :foo 1775415357.234 sable_services #x1B[2m2026-04-05T18:55:57.234298Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704532736249864)), timestamp: 1775415357, clock: EventClock(1 => 828704529154314247, 99 => 828704516861210626), target: User(UserId(Snowflake(828704532736249861))), details: NewUser(NewUser { nickname: Nickname("otherNick"), 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(828704532736249862)), NewUserConnection { user: UserId(Snowflake(828704532736249861)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415357 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(828704532736249864)), timestamp: 1775415357, clock: EventClock(1 => 828704529154314247, 99 => 828704516861210626), target: User(UserId(Snowflake(828704532736249861))), details: NewUser(NewUser { nickname: Nickname("otherNick"), 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(828704532736249862)), NewUserConnection { user: UserId(Snowflake(828704532736249861)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415357 })) }) }; event clock=EventClock(1 => 828704529154314247, 99 => 828704516861210626) my clock=EventClock(1 => 828704529154314247, 99 => 828704516861210626) 1775415357.235 sable_services #x1B[2m2026-04-05T18:55:57.234716Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704532736249864)), timestamp: 1775415357, clock: EventClock(1 => 828704529154314247, 99 => 828704516861210626), target: User(UserId(Snowflake(828704532736249861))), details: NewUser(NewUser { nickname: Nickname("otherNick"), 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(828704532736249862)), NewUserConnection { user: UserId(Snowflake(828704532736249861)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415357 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704532736249864)), timestamp: 1775415357, clock: EventClock(1 => 828704529154314247, 99 => 828704516861210626), target: User(UserId(Snowflake(828704532736249861))), details: NewUser(NewUser { nickname: Nickname("otherNick"), 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(828704532736249862)), NewUserConnection { user: UserId(Snowflake(828704532736249861)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1775415357 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415357.294 2 -> S: PING synchronize597.441265943 1775415357.294 S -> 2: :My.Little.Server 002 otherNick :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1775415357.294 S -> 2: :My.Little.Server 003 otherNick :This server was created 2026-04-05 18:55:57.193452117 UTC 1775415357.294 S -> 2: :My.Little.Server 004 otherNick My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1775415357.294 S -> 2: :My.Little.Server 005 otherNick 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 1775415357.294 S -> 2: :My.Little.Server 422 otherNick :MOTD File is missing 1775415357.294 S -> 2: :otherNick!username@localhost MODE otherNick + 1775415357.294 S -> 2: :My.Little.Server NOTICE otherNick :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1775415357.294 S -> 2: :My.Little.Server PONG My.Little.Server :foo 1775415357.295 sable_ircd #x1B[2m2026-04-05T18:55:57.294501Z#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 1775415357.295 sable_ircd #x1B[2m2026-04-05T18:55:57.294543Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize597.441265943" 1775415357.295 sable_ircd #x1B[2m2026-04-05T18:55:57.294617Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server PONG My.Little.Server :synchronize597.441265943 1775415357.295 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize597.441265943 1775415358.096 2 -> S: PING synchronize598.243036969 1775415358.097 sable_ircd #x1B[2m2026-04-05T18:55:58.096495Z#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 1775415358.097 sable_ircd #x1B[2m2026-04-05T18:55:58.096563Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize598.243036969" 1775415358.097 sable_ircd #x1B[2m2026-04-05T18:55:58.096697Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server PONG My.Little.Server :synchronize598.243036969 1775415358.097 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize598.243036969 1775415358.097 2 -> S: JOIN #chan 1775415358.097 sable_ircd #x1B[2m2026-04-05T18:55:58.097308Z#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 1775415358.097 sable_ircd #x1B[2m2026-04-05T18:55:58.097347Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"JOIN #chan" 1775415358.197 2 -> S: PING synchronize598.344303773 1775415358.198 sable_ircd #x1B[2m2026-04-05T18:55:58.197677Z#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 1775415358.198 sable_ircd #x1B[2m2026-04-05T18:55:58.197728Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize598.344303773" 1775415359.198 2: waiting… 1775415359.202 sable_ircd #x1B[2m2026-04-05T18:55:59.202339Z#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(828704541175189513)), timestamp: 1775415359, clock: EventClock(1 => 828704532736249864, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704532736249861)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704532736249861)), permissions: MembershipFlagSet(0) }) }; event clock=EventClock(1 => 828704532736249864, 99 => 828704516861210626) my clock=EventClock(1 => 828704532736249864, 99 => 828704516861210626) 1775415359.203 sable_ircd #x1B[2m2026-04-05T18:55:59.202552Z#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 1775415359.203 sable_ircd #x1B[2m2026-04-05T18:55:59.202817Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704541175189513)), timestamp: 1775415359, clock: EventClock(1 => 828704532736249864, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704532736249861)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704532736249861)), permissions: MembershipFlagSet(0) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:45445 (127.0.0.1:45445) 1775415359.205 sable_ircd #x1B[2m2026-04-05T18:55:59.205006Z#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(828704541175189513)), timestamp: 1775415359, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(828704532736249861)), ChannelId(Snowflake(828704529145925636))), user: HistoricUserId(UserId(Snowflake(828704532736249861)), 2) }), users_to_notify: [UserId(Snowflake(828704528680357890)), UserId(Snowflake(828704532736249861))] }) 1775415359.205 sable_ircd #x1B[2m2026-04-05T18:55:59.205059Z#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(828704541175189513)), timestamp: 1775415359, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(828704532736249861)), ChannelId(Snowflake(828704529145925636))), user: HistoricUserId(UserId(Snowflake(828704532736249861)), 2) }), users_to_notify: [UserId(Snowflake(828704528680357890)), UserId(Snowflake(828704532736249861))] } 1775415359.205 sable_ircd #x1B[2m2026-04-05T18:55:59.205135Z#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): :otherNick!username@localhost JOIN #chan 1775415359.205 sable_ircd #x1B[2m2026-04-05T18:55:59.205206Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :otherNick!username@localhost JOIN #chan 1775415359.205 sable_ircd #x1B[2m2026-04-05T18:55:59.205279Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 353 otherNick = #chan :@coolNick otherNick 1775415359.205 sable_ircd #x1B[2m2026-04-05T18:55:59.205320Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 366 otherNick #chan :End of names list 1775415359.205 sable_ircd #x1B[2m2026-04-05T18:55:59.205392Z#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(828704541175189513)), timestamp: 1775415359, change: EventComplete(EventComplete), users_to_notify: [] }) 1775415359.205 sable_ircd #x1B[2m2026-04-05T18:55:59.205427Z#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(828704541175189513)), timestamp: 1775415359, change: EventComplete(EventComplete), users_to_notify: [] } 1775415359.206 S -> 2: :otherNick!username@localhost JOIN #chan 1775415359.246 S -> 2: :My.Little.Server 353 otherNick = #chan :@coolNick otherNick 1775415359.246 S -> 2: :My.Little.Server 366 otherNick #chan :End of names list 1775415359.246 sable_services #x1B[2m2026-04-05T18:55:59.246255Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704541175189513)), timestamp: 1775415359, clock: EventClock(1 => 828704532736249864, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704532736249861)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704532736249861)), permissions: MembershipFlagSet(0) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(828704541175189513)), timestamp: 1775415359, clock: EventClock(1 => 828704532736249864, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704532736249861)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704532736249861)), permissions: MembershipFlagSet(0) }) }; event clock=EventClock(1 => 828704532736249864, 99 => 828704516861210626) my clock=EventClock(1 => 828704532736249864, 99 => 828704516861210626) 1775415359.247 sable_services #x1B[2m2026-04-05T18:55:59.246620Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704541175189513)), timestamp: 1775415359, clock: EventClock(1 => 828704532736249864, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704532736249861)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704532736249861)), permissions: MembershipFlagSet(0) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1775415353), content: NewEvent(Event { id: EventId(Snowflake(828704541175189513)), timestamp: 1775415359, clock: EventClock(1 => 828704532736249864, 99 => 828704516861210626), target: Membership(MembershipId(UserId(Snowflake(828704532736249861)), ChannelId(Snowflake(828704529145925636)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(828704529145925636)), user: UserId(Snowflake(828704532736249861)), permissions: MembershipFlagSet(0) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36691 (127.0.0.1:36691) 1775415360.211 sable_ircd #x1B[2m2026-04-05T18:56:00.210835Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server PONG My.Little.Server :synchronize598.344303773 1775415360.211 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize598.344303773 1775415360.214 sable_ircd #x1B[2m2026-04-05T18:56:00.213932Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1mhandle_connection#x1B[0m#x1B[1m{#x1B[0m#x1B[3mconn#x1B[0m#x1B[2m=#x1B[0mPollEvented { io: Some(TcpStream { addr: 127.0.0.1:36691, peer: 127.0.0.1:56325, fd: 19 }) } #x1B[3mmessage_sender#x1B[0m#x1B[2m=#x1B[0mUnboundedSender { chan: Tx { inner: Chan { tx: Tx { block_tail: 0x55630499c010, tail_position: 0 }, semaphore: Semaphore(0), rx_waker: AtomicWaker, tx_count: 2, rx_fields: "..." } } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error handling message: I/O error: unexpected end of file 1775415360.219 1: disconnects from server. 1775415360.219 2: disconnects from server.