--------------------------------- Captured Out ---------------------------------
---- new test ----
1772747636.137 sable_ircd     uid=1001
1772747636.138 sable_services uid=1001
1772747636.139 sable_services #x1B[2m2026-03-05T21:53:56.138601Z#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:45613", fingerprint: "635e079ff631d7ad935e30bb536e25288db828e4" }
1772747636.139 sable_services #x1B[2m2026-03-05T21:53:56.138799Z#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), 1772747636), 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:45613. Last error: I/O error: Connection refused (os error 111)
1772747636.139 sable_services #x1B[2m2026-03-05T21:53:56.138850Z#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), 1772747636), 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)
1772747636.141 sable_ircd     #x1B[2m2026-03-05T21:53:56.140946Z#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(817515299665350656)), timestamp: 1772747636, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Server"), ts: 1772747636, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1772747636.141 sable_ircd     #x1B[2m2026-03-05T21:53:56.141061Z#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
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.141454Z#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(817515299665350656)), timestamp: 1772747636, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Server"), ts: 1772747636, 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
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.141615Z#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(817515299665350656)), timestamp: 1772747636, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.141690Z#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(817515299665350656)), timestamp: 1772747636, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.141796Z#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
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.141877Z#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(817515299665350656)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] })
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.141923Z#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(817515299669544961)), timestamp: 1772747636, clock: EventClock(1 => 817515299665350656), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1772747636 }) }; event clock=EventClock(1 => 817515299665350656) my clock=EventClock(1 => 817515299665350656)
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.141959Z#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(817515299665350656)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] }
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.142012Z#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
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.142236Z#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(817515299669544961)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] })
1772747636.142 sable_ircd     #x1B[2m2026-03-05T21:53:56.142268Z#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(817515299669544961)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] }
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.233576Z#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 }
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.233755Z#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
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.233855Z#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
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.233942Z#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
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.234113Z#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
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.234179Z#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"
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.234298Z#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
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.234415Z#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))
1772747636.234 sable_ircd     #x1B[2m2026-03-05T21:53:56.234444Z#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)
1772747636.235 sable_ircd     #x1B[2m2026-03-05T21:53:56.234894Z#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
1772747636.235 sable_ircd     #x1B[2m2026-03-05T21:53:56.234975Z#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
1772747636.235 sable_ircd     #x1B[2m2026-03-05T21:53:56.235155Z#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
1772747636.251 sable_services #x1B[2m2026-03-05T21:53:56.251296Z#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:45613", fingerprint: "635e079ff631d7ad935e30bb536e25288db828e4" }
1772747636.252 sable_services #x1B[2m2026-03-05T21:53:56.251548Z#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), 1772747636), 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:45613 (127.0.0.1:45613)
1772747636.255 chkSvs: connects to server.
1772747636.255 chkSvs -> S: NICK chkSvs
1772747636.255 chkSvs -> S: USER chk chk chk chk
1772747636.255 sable_ircd     #x1B[2m2026-03-05T21:53:56.255094Z#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 }
1772747636.255 sable_ircd     #x1B[2m2026-03-05T21:53:56.255231Z#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
1772747636.255 sable_ircd     #x1B[2m2026-03-05T21:53:56.255290Z#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
1772747636.255 sable_ircd     #x1B[2m2026-03-05T21:53:56.255369Z#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
1772747636.256 sable_ircd     #x1B[2m2026-03-05T21:53:56.255523Z#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
1772747636.256 sable_ircd     #x1B[2m2026-03-05T21:53:56.255578Z#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"
1772747636.256 sable_ircd     #x1B[2m2026-03-05T21:53:56.255684Z#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
1772747636.256 sable_ircd     #x1B[2m2026-03-05T21:53:56.255732Z#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"
1772747636.256 sable_ircd     #x1B[2m2026-03-05T21:53:56.255849Z#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: 473, tv_nsec: 591998649 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(<uninit>), realname: OnceLock(Realname("chk")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747636.256 sable_ircd     #x1B[2m2026-03-05T21:53:56.255985Z#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: 473, tv_nsec: 591998649 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747636.256 sable_ircd     #x1B[2m2026-03-05T21:53:56.256057Z#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
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.256573Z#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
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.256626Z#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"))
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.256683Z#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
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.256720Z#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: 473, tv_nsec: 591998649 }, attach_user_id: OnceLock(<uninit>), 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(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.256774Z#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
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.256787Z#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))
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.256962Z#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(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }; event clock=EventClock(1 => 817515299669544961) my clock=EventClock(1 => 817515299669544961)
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.257059Z#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
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.257366Z#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(817515300151889922)), timestamp: 1772747636, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515300151889920)), 1) }), users_to_notify: [] })
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.257402Z#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(817515300151889922)), timestamp: 1772747636, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515300151889920)), 1) }), users_to_notify: [] }
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.257438Z#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(817515300151889922)), timestamp: 1772747636, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515300151889920)), 2), connection: UserConnectionId(Snowflake(817515300151889921)) }), users_to_notify: [] })
1772747636.257 sable_ircd     #x1B[2m2026-03-05T21:53:56.257459Z#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(817515300151889922)), timestamp: 1772747636, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515300151889920)), 2), connection: UserConnectionId(Snowflake(817515300151889921)) }), users_to_notify: [] }
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257499Z#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
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257524Z#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
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257546Z#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-03-05 21:53:56.257538678 UTC
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257564Z#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
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257599Z#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
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257619Z#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
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257638Z#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 +
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257654Z#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.
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257679Z#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(817515300151889922)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] })
1772747636.258 sable_ircd     #x1B[2m2026-03-05T21:53:56.257697Z#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(817515300151889922)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] }
1772747636.298 sable_services #x1B[2m2026-03-05T21:53:56.297929Z#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), 1772747636), content: NetworkState(Network { nick_bindings: {}, historic_nick_users: HistoricNickStore { data: {} }, users: {}, historic_users: HistoricUserStore { users: {} }, user_connections: {}, channels: {}, list_mode_entries: {}, channel_topics: {}, channel_invites: {}, memberships: {}, messages: {}, servers: {ServerId(1): Server { id: ServerId(1), epoch: 1772747636, name: ServerName("My.Little.Server"), last_ping: 1772747636, 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: {"host": (3, String(_)), "user_param_2": (0, String(_)), "tls": (0, Boolean(_)), "user": (1, String(_)), "realname": (5, String(_)), "nick": (4, String(_)), "user_param_1": (2, String(_)), "ip": (0, Ip(_))} }, 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: {"tls": (0, Boolean(_)), "ip": (0, Ip(_))} }, 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: {"tls": (0, Boolean(_)), "ip": (0, Ip(_)), "mechanism": (0, String(_))} } }, 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: {BuiltinAll: ChannelAccessSet(1114112), BuiltinVoice: ChannelAccessSet(4296081441), BuiltinOp: ChannelAccessSet(222192934775)}, 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 => 817515299669544961), cache_default_channel_roles: OnceLock(<uninit>), alias_users: OnceLock(<uninit>) }) }
1772747636.298 sable_services #x1B[2m2026-03-05T21:53:56.298105Z#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
1772747636.298 sable_services #x1B[2m2026-03-05T21:53:56.298220Z#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
1772747636.299 sable_services #x1B[2m2026-03-05T21:53:56.298738Z#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(817515300328452096)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Services"), ts: 1772747636, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 817515299669544961) my clock=EventClock(1 => 817515299669544961)
1772747636.299 sable_services #x1B[2m2026-03-05T21:53:56.299113Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300328452096)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Services"), ts: 1772747636, 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:45613 (127.0.0.1:45613)
1772747636.301 sable_services #x1B[2m2026-03-05T21:53:56.301038Z#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(817515300341035009)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300328452096), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747636 }) }; event clock=EventClock(1 => 817515299669544961, 99 => 817515300328452096) my clock=EventClock(1 => 817515299669544961, 99 => 817515300328452096)
1772747636.302 sable_services #x1B[2m2026-03-05T21:53:56.301393Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300341035009)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300328452096), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747636 }) }) } #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:45613 (127.0.0.1:45613)
1772747636.302 sable_services #x1B[2m2026-03-05T21:53:56.301484Z#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(817515300328452096)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Services"), ts: 1772747636, 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
1772747636.305 sable_services #x1B[2m2026-03-05T21:53:56.304939Z#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(817515300353617922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300341035009), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 817515299669544961, 99 => 817515300341035009) my clock=EventClock(1 => 817515299669544961, 99 => 817515300341035009)
1772747636.306 sable_services #x1B[2m2026-03-05T21:53:56.305648Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300353617922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300341035009), 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:45613 (127.0.0.1:45613)
1772747636.306 sable_ircd     #x1B[2m2026-03-05T21:53:56.306050Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300341035009)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300328452096), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747636 }) }) } }#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(817515300341035009)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300328452096), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747636 }) }; event clock=EventClock(1 => 817515299669544961, 99 => 817515300328452096) my clock=EventClock(1 => 817515300151889922)
1772747636.306 sable_ircd     #x1B[2m2026-03-05T21:53:56.306153Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300341035009)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300328452096), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747636 }) }) } }#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(817515299669544961)), EventId(Snowflake(817515300328452096))]
1772747636.306 sable_ircd     #x1B[2m2026-03-05T21:53:56.306222Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300341035009)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300328452096), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747636 }) }) } }#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
1772747636.342 sable_ircd     #x1B[2m2026-03-05T21:53:56.341734Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300328452096)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Services"), ts: 1772747636, 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(817515300328452096)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Services"), ts: 1772747636, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 817515299669544961) my clock=EventClock(1 => 817515300151889922)
1772747636.342 sable_ircd     #x1B[2m2026-03-05T21:53:56.341822Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300328452096)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Services"), ts: 1772747636, 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(817515300341035009)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300328452096), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747636 }) }
1772747636.342 sable_ircd     #x1B[2m2026-03-05T21:53:56.341879Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300328452096)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Services"), ts: 1772747636, 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
1772747636.342 sable_ircd     #x1B[2m2026-03-05T21:53:56.342175Z#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(817515300328452096)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747636, name: ServerName("My.Little.Services"), ts: 1772747636, 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
1772747636.343 sable_ircd     #x1B[2m2026-03-05T21:53:56.342456Z#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(817515300328452096)), timestamp: 1772747636, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] })
1772747636.343 sable_ircd     #x1B[2m2026-03-05T21:53:56.342499Z#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(817515300328452096)), timestamp: 1772747636, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] }
1772747636.343 sable_ircd     #x1B[2m2026-03-05T21:53:56.342540Z#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(817515300328452096)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] })
1772747636.343 sable_ircd     #x1B[2m2026-03-05T21:53:56.342562Z#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(817515300328452096)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] }
1772747636.343 sable_ircd     #x1B[2m2026-03-05T21:53:56.342588Z#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(817515300341035009)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] })
1772747636.343 sable_ircd     #x1B[2m2026-03-05T21:53:56.342607Z#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(817515300341035009)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] }
1772747636.348 sable_services #x1B[2m2026-03-05T21:53:56.347597Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515299669544961)), EventId(Snowflake(817515300328452096))]) } }#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(817515299669544961)), EventId(Snowflake(817515300328452096))]
1772747636.348 sable_services #x1B[2m2026-03-05T21:53:56.347650Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515299669544961)), EventId(Snowflake(817515300328452096))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events []
1772747636.349 sable_ircd     #x1B[2m2026-03-05T21:53:56.348585Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300353617922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300341035009), 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(817515300353617922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300341035009), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 817515299669544961, 99 => 817515300341035009) my clock=EventClock(1 => 817515300151889922, 99 => 817515300341035009)
1772747636.349 sable_ircd     #x1B[2m2026-03-05T21:53:56.348971Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300353617922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300341035009), 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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515300353617922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961, 99 => 817515300341035009), 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:59811 (127.0.0.1:59811)
1772747636.353 sable_ircd     #x1B[2m2026-03-05T21:53:56.352771Z#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(817515300353617922)), timestamp: 1772747636, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] })
1772747636.353 sable_ircd     #x1B[2m2026-03-05T21:53:56.352809Z#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(817515300353617922)), timestamp: 1772747636, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] }
1772747636.353 sable_ircd     #x1B[2m2026-03-05T21:53:56.352941Z#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(817515300353617922)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] })
1772747636.353 sable_ircd     #x1B[2m2026-03-05T21:53:56.352981Z#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(817515300353617922)), timestamp: 1772747636, change: EventComplete(EventComplete), users_to_notify: [] }
1772747636.355 S -> chkSvs: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747636.355 S -> chkSvs: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747636.355 S -> chkSvs: :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs
1772747636.355 S -> chkSvs: :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747636.355 S -> chkSvs: :My.Little.Server 003 chkSvs :This server was created 2026-03-05 21:53:56.257538678 UTC
1772747636.355 S -> chkSvs: :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747636.355 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
1772747636.355 S -> chkSvs: :My.Little.Server 422 chkSvs :MOTD File is missing
1772747636.355 S -> chkSvs: :chkSvs!chk@localhost MODE chkSvs +
1772747636.355 S -> chkSvs: :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747636.355 chkSvs -> S: CAP LS 302
1772747636.356 sable_ircd     #x1B[2m2026-03-05T21:53:56.355580Z#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
1772747636.356 sable_ircd     #x1B[2m2026-03-05T21:53:56.355621Z#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"
1772747636.356 sable_ircd     #x1B[2m2026-03-05T21:53:56.355723Z#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
1772747636.389 sable_ircd     #x1B[2m2026-03-05T21:53:56.388645Z#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), 1772747636), 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: []
1772747636.405 chkSvs -> S: PING synchronize473.741927426
1772747636.405 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
1772747636.406 sable_ircd     #x1B[2m2026-03-05T21:53:56.405638Z#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
1772747636.406 sable_ircd     #x1B[2m2026-03-05T21:53:56.405679Z#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 synchronize473.741927426"
1772747636.406 sable_ircd     #x1B[2m2026-03-05T21:53:56.405765Z#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 :synchronize473.741927426
1772747636.406 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize473.741927426
1772747636.406 chkSvs -> S: QUIT
1772747636.406 chkSvs -> S: PING synchronize473.74277244
1772747636.406 sable_ircd     #x1B[2m2026-03-05T21:53:56.406362Z#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
1772747636.406 sable_ircd     #x1B[2m2026-03-05T21:53:56.406401Z#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"
1772747636.406 sable_ircd     #x1B[2m2026-03-05T21:53:56.406460Z#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
1772747636.407 sable_ircd     #x1B[2m2026-03-05T21:53:56.406491Z#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 synchronize473.74277244"
1772747637.159 sable_ircd     #x1B[2m2026-03-05T21:53:57.159254Z#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
1772747637.160 sable_ircd     #x1B[2m2026-03-05T21:53:57.159455Z#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))
1772747637.160 sable_ircd     #x1B[2m2026-03-05T21:53:57.159516Z#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)
1772747637.160 S -> chkSvs: ERROR :Client quit
1772747637.160 sable_ircd     #x1B[2m2026-03-05T21:53:57.159669Z#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(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515300151889922, 99 => 817515300353617922) my clock=EventClock(1 => 817515300151889922, 99 => 817515300353617922)
1772747637.160 chkSvs: disconnects from server.
1772747637.160 1: connects to server.
1772747637.160 1 -> S: NICK jilles
1772747637.160 1 -> S: USER r e g :user
1772747637.160 sable_ircd     #x1B[2m2026-03-05T21:53:57.159967Z#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
1772747637.160 sable_ircd     #x1B[2m2026-03-05T21:53:57.160049Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), 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:59811 (127.0.0.1:59811)
1772747637.160 sable_ircd     #x1B[2m2026-03-05T21:53:57.160075Z#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
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.160556Z#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 }
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.160722Z#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
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.160775Z#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
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.160843Z#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
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.160971Z#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
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.161030Z#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 jilles"
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.161180Z#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: 474, tv_nsec: 497489647 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("jilles")), realname: OnceLock(<uninit>), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747637.161 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.161256Z#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
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.161312Z#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
1772747637.161 sable_ircd     #x1B[2m2026-03-05T21:53:57.161381Z#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 r e g :user"
1772747637.162 sable_ircd     #x1B[2m2026-03-05T21:53:57.161463Z#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: 474, tv_nsec: 497489647 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(Nickname("jilles")), realname: OnceLock(Realname("user")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747637.162 sable_ircd     #x1B[2m2026-03-05T21:53:57.162017Z#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
1772747637.162 sable_ircd     #x1B[2m2026-03-05T21:53:57.162055Z#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"))
1772747637.162 sable_ircd     #x1B[2m2026-03-05T21:53:57.162100Z#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
1772747637.162 sable_ircd     #x1B[2m2026-03-05T21:53:57.162136Z#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: 474, tv_nsec: 497489647 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(Nickname("jilles")), realname: OnceLock(Realname("user")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747637.162 sable_ircd     #x1B[2m2026-03-05T21:53:57.162196Z#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))
1772747637.162 sable_ircd     #x1B[2m2026-03-05T21:53:57.162299Z#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(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515303939346435, 99 => 817515300353617922) my clock=EventClock(1 => 817515303939346435, 99 => 817515300353617922)
1772747637.163 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747637.163 sable_ircd     #x1B[2m2026-03-05T21:53:57.162600Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), 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:59811 (127.0.0.1:59811)
1772747637.166 sable_ircd     #x1B[2m2026-03-05T21:53:57.166039Z#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(817515303968706565)), timestamp: 1772747637, clock: EventClock(1 => 817515303951929348, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515303951929347)), NewUserConnection { user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 })) }) }; event clock=EventClock(1 => 817515303951929348, 99 => 817515300353617922) my clock=EventClock(1 => 817515303951929348, 99 => 817515300353617922)
1772747637.167 sable_ircd     #x1B[2m2026-03-05T21:53:57.166356Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303968706565)), timestamp: 1772747637, clock: EventClock(1 => 817515303951929348, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515303951929347)), NewUserConnection { user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 })) }) }) } #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:59811 (127.0.0.1:59811)
1772747637.167 sable_ircd     #x1B[2m2026-03-05T21:53:57.166513Z#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(817515303939346435)), timestamp: 1772747637, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515300151889920)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(817515300151889921)), user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 } }), users_to_notify: [] })
1772747637.167 sable_ircd     #x1B[2m2026-03-05T21:53:57.166559Z#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(817515303939346435)), timestamp: 1772747637, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515300151889920)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(817515300151889921)), user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 } }), users_to_notify: [] }
1772747637.167 sable_ircd     #x1B[2m2026-03-05T21:53:57.166622Z#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(817515303939346435)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] })
1772747637.167 sable_ircd     #x1B[2m2026-03-05T21:53:57.166640Z#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(817515303939346435)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] }
1772747637.167 sable_services #x1B[2m2026-03-05T21:53:57.167109Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), 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(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515303939346435, 99 => 817515300353617922) my clock=EventClock(1 => 817515299669544961, 99 => 817515300353617922)
1772747637.167 sable_services #x1B[2m2026-03-05T21:53:57.167191Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), 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(817515303939346435))]
1772747637.168 sable_services #x1B[2m2026-03-05T21:53:57.167722Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), 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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), 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:45613 (127.0.0.1:45613)
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.168905Z#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(817515303951929348)), timestamp: 1772747637, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515300151889920)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] })
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.168955Z#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(817515303951929348)), timestamp: 1772747637, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515300151889920)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] }
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169020Z#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(817515303951929348)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] })
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169055Z#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(817515303951929348)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] }
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169133Z#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(817515303968706565)), timestamp: 1772747637, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 1) }), users_to_notify: [] })
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169178Z#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(817515303968706565)), timestamp: 1772747637, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 1) }), users_to_notify: [] }
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169246Z#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(817515303968706565)), timestamp: 1772747637, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 2), connection: UserConnectionId(Snowflake(817515303951929347)) }), users_to_notify: [] })
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169291Z#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(817515303968706565)), timestamp: 1772747637, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 2), connection: UserConnectionId(Snowflake(817515303951929347)) }), users_to_notify: [] }
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169374Z#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 jilles :Welcome to the test Internet Relay Chat network, jilles
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169413Z#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 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169443Z#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 jilles :This server was created 2026-03-05 21:53:57.169433108 UTC
1772747637.169 sable_ircd     #x1B[2m2026-03-05T21:53:57.169467Z#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 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747637.170 sable_ircd     #x1B[2m2026-03-05T21:53:57.169496Z#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 jilles 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
1772747637.170 sable_ircd     #x1B[2m2026-03-05T21:53:57.169521Z#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 jilles :MOTD File is missing
1772747637.170 sable_ircd     #x1B[2m2026-03-05T21:53:57.169546Z#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): :jilles!r@localhost MODE jilles +
1772747637.170 sable_ircd     #x1B[2m2026-03-05T21:53:57.169567Z#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 jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747637.170 sable_ircd     #x1B[2m2026-03-05T21:53:57.169602Z#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(817515303968706565)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] })
1772747637.170 sable_ircd     #x1B[2m2026-03-05T21:53:57.169628Z#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(817515303968706565)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] }
1772747637.170 S -> 1: :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles
1772747637.170 S -> 1: :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747637.170 S -> 1: :My.Little.Server 003 jilles :This server was created 2026-03-05 21:53:57.169433108 UTC
1772747637.205 sable_services #x1B[2m2026-03-05T21:53:57.204830Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), 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 Deferring event Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515300151889922, 99 => 817515300353617922) my clock=EventClock(1 => 817515299669544961, 99 => 817515300353617922)
1772747637.205 sable_services #x1B[2m2026-03-05T21:53:57.204918Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), 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::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(817515300151889922))]
1772747637.205 sable_services #x1B[2m2026-03-05T21:53:57.205370Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), 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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), 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:45613 (127.0.0.1:45613)
1772747637.209 sable_ircd     #x1B[2m2026-03-05T21:53:57.208560Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515303939346435))]) } }#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(817515303939346435))]
1772747637.209 sable_ircd     #x1B[2m2026-03-05T21:53:57.208602Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515303939346435))]) } }#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(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), details: UserDisconnect(UserDisconnect) }]
1772747637.210 sable_services #x1B[2m2026-03-05T21:53:57.209607Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303968706565)), timestamp: 1772747637, clock: EventClock(1 => 817515303951929348, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515303951929347)), NewUserConnection { user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 })) }) }) } }#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(817515303968706565)), timestamp: 1772747637, clock: EventClock(1 => 817515303951929348, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515303951929347)), NewUserConnection { user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 })) }) }; event clock=EventClock(1 => 817515303951929348, 99 => 817515300353617922) my clock=EventClock(1 => 817515299669544961, 99 => 817515300353617922)
1772747637.210 sable_services #x1B[2m2026-03-05T21:53:57.209661Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303968706565)), timestamp: 1772747637, clock: EventClock(1 => 817515303951929348, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515303951929347)), NewUserConnection { user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 })) }) }) } }#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(817515303951929348))]
1772747637.210 sable_services #x1B[2m2026-03-05T21:53:57.209991Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303968706565)), timestamp: 1772747637, clock: EventClock(1 => 817515303951929348, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515303951929347)), NewUserConnection { user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 })) }) }) } }#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515303968706565)), timestamp: 1772747637, clock: EventClock(1 => 817515303951929348, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515303951929347)), NewUserConnection { user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 })) }) }) } #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:45613 (127.0.0.1:45613)
1772747637.247 sable_ircd     #x1B[2m2026-03-05T21:53:57.246667Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515300151889922))]) } }#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(817515300151889922))]
1772747637.247 sable_ircd     #x1B[2m2026-03-05T21:53:57.246731Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515300151889922))]) } }#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(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]
1772747637.250 sable_services #x1B[2m2026-03-05T21:53:57.249671Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), 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(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), details: UserDisconnect(UserDisconnect) }]
1772747637.250 sable_services #x1B[2m2026-03-05T21:53:57.249775Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), 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[0mfalse#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(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515300151889922, 99 => 817515300353617922) my clock=EventClock(1 => 817515299669544961, 99 => 817515300353617922)
1772747637.250 sable_services #x1B[2m2026-03-05T21:53:57.249856Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), 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[0mfalse#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(817515300151889922))]
1772747637.251 sable_ircd     #x1B[2m2026-03-05T21:53:57.250625Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515303951929348))]) } }#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(817515303951929348))]
1772747637.251 sable_ircd     #x1B[2m2026-03-05T21:53:57.250682Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515303951929348))]) } }#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(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), details: UserQuit(UserQuit { message: "Client Quit" }) }]
1772747637.270 1 -> S: PING synchronize474.60676588
1772747637.270 S -> 1: :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747637.270 S -> 1: :My.Little.Server 005 jilles 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
1772747637.270 S -> 1: :My.Little.Server 422 jilles :MOTD File is missing
1772747637.270 S -> 1: :jilles!r@localhost MODE jilles +
1772747637.270 S -> 1: :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747637.271 sable_ircd     #x1B[2m2026-03-05T21:53:57.270618Z#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
1772747637.271 sable_ircd     #x1B[2m2026-03-05T21:53:57.270672Z#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 synchronize474.60676588"
1772747637.271 sable_ircd     #x1B[2m2026-03-05T21:53:57.270780Z#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 :synchronize474.60676588
1772747637.271 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize474.60676588
1772747637.271 1 -> S: REGISTER * * sesame
1772747637.271 sable_ircd     #x1B[2m2026-03-05T21:53:57.271388Z#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
1772747637.271 sable_ircd     #x1B[2m2026-03-05T21:53:57.271455Z#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"REGISTER * * sesame"
1772747637.272 sable_ircd     #x1B[2m2026-03-05T21:53:57.271741Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Found target peer, sending message #x1B[3mtarget#x1B[0m#x1B[2m=#x1B[0mPeerConfig { name: ServerName("My.Little.Services"), address: "127.0.0.1:59811", fingerprint: "874a0844acad05a0f6e9d427b95c8b987d78ea81" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(RegisterUser(Nickname("jilles"), "sesame")) }
1772747637.272 sable_ircd     #x1B[2m2026-03-05T21:53:57.272012Z#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), 1772747636), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(RegisterUser(Nickname("jilles"), "sesame")) }) } #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:59811 (127.0.0.1:59811)
1772747637.274 sable_ircd     #x1B[2m2026-03-05T21:53:57.273741Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(26) })
1772747637.288 sable_services #x1B[2m2026-03-05T21:53:57.287741Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]) } }#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(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]
1772747637.288 sable_services #x1B[2m2026-03-05T21:53:57.287810Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]) } }#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[0mfalse#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(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }; event clock=EventClock(1 => 817515299669544961) my clock=EventClock(1 => 817515299669544961, 99 => 817515300353617922)
1772747637.288 sable_services #x1B[2m2026-03-05T21:53:57.287895Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]) } }#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[0mfalse#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(817515303939346435)), timestamp: 1772747637, clock: EventClock(1 => 817515300151889922, 99 => 817515300353617922), target: UserConnection(UserConnectionId(Snowflake(817515300151889921))), details: UserDisconnect(UserDisconnect) }
1772747637.288 sable_services #x1B[2m2026-03-05T21:53:57.287936Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]) } }#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[0mfalse#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(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), details: UserQuit(UserQuit { message: "Client Quit" }) }
1772747637.288 sable_services #x1B[2m2026-03-05T21:53:57.287967Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]) } }#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[0mfalse#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(817515303968706565)), timestamp: 1772747637, clock: EventClock(1 => 817515303951929348, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515303951929347)), NewUserConnection { user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 })) }) }
1772747637.292 sable_services #x1B[2m2026-03-05T21:53:57.291557Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), 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(817515303951929348)), timestamp: 1772747637, clock: EventClock(1 => 817515303939346435, 99 => 817515300353617922), target: User(UserId(Snowflake(817515300151889920))), details: UserQuit(UserQuit { message: "Client Quit" }) }]
1772747637.315 sable_services #x1B[2m2026-03-05T21:53:57.314721Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(RegisterUser(Nickname("jilles"), "sesame"))
1772747637.315 sable_services #x1B[2m2026-03-05T21:53:57.314764Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got register request #x1B[3maccount_name#x1B[0m#x1B[2m=#x1B[0mNickname("jilles")
1772747637.319 sable_services #x1B[2m2026-03-05T21:53:57.319164Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server::command::user_commands#x1B[0m#x1B[2m:#x1B[0m Successfully created account #x1B[3mnew_account#x1B[0m#x1B[2m=#x1B[0mAccount { id: AccountId(Snowflake(817515304589864960)), name: Nickname("jilles"), authorised_fingerprints: [] }
1772747637.319 sable_services #x1B[2m2026-03-05T21:53:57.319397Z#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(817515304610836483)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: Account(AccountId(Snowflake(817515304589864960))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515304589864960)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 817515303968706565, 99 => 817515300353617922) my clock=EventClock(1 => 817515303968706565, 99 => 817515300353617922)
1772747637.320 sable_services #x1B[2m2026-03-05T21:53:57.319864Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515304610836483)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: Account(AccountId(Snowflake(817515304589864960))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515304589864960)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } #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:45613 (127.0.0.1:45613)
1772747637.361 sable_ircd     #x1B[2m2026-03-05T21:53:57.360561Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got targeted message response #x1B[3mresponse#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747636), content: TargetedMessageResponse(Services(LogUserIn(AccountId(Snowflake(817515304589864960))))) } }
1772747637.361 sable_ircd     #x1B[2m2026-03-05T21:53:57.360720Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515300151889922))]) } }#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(817515300151889922))]
1772747637.361 sable_ircd     #x1B[2m2026-03-05T21:53:57.360767Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515300151889922))]) } }#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(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]
1772747637.361 sable_ircd     #x1B[2m2026-03-05T21:53:57.360956Z#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): REGISTER SUCCESS jilles :You have successfully registered
1772747637.361 sable_ircd     #x1B[2m2026-03-05T21:53:57.361019Z#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
1772747637.361 sable_ircd     #x1B[2m2026-03-05T21:53:57.361068Z#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[0mStateChange(User(UserId(Snowflake(817515303951929346))), UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515304589864960))) }))
1772747637.361 sable_ircd     #x1B[2m2026-03-05T21:53:57.361194Z#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(817515304786595846)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515304589864960))) }) }; event clock=EventClock(1 => 817515303968706565, 99 => 817515300353617922) my clock=EventClock(1 => 817515303968706565, 99 => 817515300353617922)
1772747637.362 sable_ircd     #x1B[2m2026-03-05T21:53:57.361489Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515304786595846)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515304589864960))) }) }) } #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:59811 (127.0.0.1:59811)
1772747637.363 sable_ircd     #x1B[2m2026-03-05T21:53:57.363363Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515304610836483)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: Account(AccountId(Snowflake(817515304589864960))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515304589864960)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } }#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(817515304610836483)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: Account(AccountId(Snowflake(817515304589864960))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515304589864960)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 817515303968706565, 99 => 817515300353617922) my clock=EventClock(1 => 817515304786595846, 99 => 817515300353617922)
1772747637.364 sable_ircd     #x1B[2m2026-03-05T21:53:57.363728Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515304610836483)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: Account(AccountId(Snowflake(817515304589864960))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515304589864960)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } }#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515304610836483)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: Account(AccountId(Snowflake(817515304589864960))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515304589864960)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } #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:59811 (127.0.0.1:59811)
1772747637.364 sable_ircd     #x1B[2m2026-03-05T21:53:57.363818Z#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(817515304786595846)), timestamp: 1772747637, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 3), old_account: None, new_account: Some(AccountId(Snowflake(817515304589864960))) }), users_to_notify: [] })
1772747637.364 sable_ircd     #x1B[2m2026-03-05T21:53:57.363858Z#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(817515304786595846)), timestamp: 1772747637, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 3), old_account: None, new_account: Some(AccountId(Snowflake(817515304589864960))) }), users_to_notify: [] }
1772747637.364 sable_ircd     #x1B[2m2026-03-05T21:53:57.363913Z#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(817515304786595846)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] })
1772747637.364 sable_ircd     #x1B[2m2026-03-05T21:53:57.363929Z#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(817515304786595846)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] }
1772747637.366 sable_ircd     #x1B[2m2026-03-05T21:53:57.365639Z#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(817515304610836483)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] })
1772747637.366 sable_ircd     #x1B[2m2026-03-05T21:53:57.365679Z#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(817515304610836483)), timestamp: 1772747637, change: EventComplete(EventComplete), users_to_notify: [] }
1772747637.402 sable_services #x1B[2m2026-03-05T21:53:57.401737Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]) } }#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(817515300151889922)), timestamp: 1772747636, clock: EventClock(1 => 817515299669544961), target: User(UserId(Snowflake(817515300151889920))), 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(817515300151889921)), NewUserConnection { user: UserId(Snowflake(817515300151889920)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747636 })) }) }]
1772747637.405 sable_services #x1B[2m2026-03-05T21:53:57.404561Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515304786595846)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515304589864960))) }) }) } }#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(817515304786595846)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515304589864960))) }) }; event clock=EventClock(1 => 817515303968706565, 99 => 817515300353617922) my clock=EventClock(1 => 817515303968706565, 99 => 817515304610836483)
1772747637.405 sable_services #x1B[2m2026-03-05T21:53:57.404823Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515304786595846)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515304589864960))) }) }) } }#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515304786595846)), timestamp: 1772747637, clock: EventClock(1 => 817515303968706565, 99 => 817515300353617922), target: User(UserId(Snowflake(817515303951929346))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515304589864960))) }) }) } #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:45613 (127.0.0.1:45613)
1772747637.471 1 -> S: PING synchronize474.807995462
1772747637.471 S -> 1: REGISTER SUCCESS jilles :You have successfully registered
1772747637.472 sable_ircd     #x1B[2m2026-03-05T21:53:57.471708Z#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
1772747637.472 sable_ircd     #x1B[2m2026-03-05T21:53:57.471757Z#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 synchronize474.807995462"
1772747638.226 sable_ircd     #x1B[2m2026-03-05T21:53:58.225549Z#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 :synchronize474.807995462
1772747638.226 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize474.807995462
1772747638.226 1 -> S: QUIT
1772747638.226 sable_ircd     #x1B[2m2026-03-05T21:53:58.226296Z#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
1772747638.226 sable_ircd     #x1B[2m2026-03-05T21:53:58.226380Z#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"QUIT"
1772747638.326 1 -> S: PING synchronize475.662832988
1772747638.327 sable_ircd     #x1B[2m2026-03-05T21:53:58.326620Z#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
1772747638.327 sable_ircd     #x1B[2m2026-03-05T21:53:58.326685Z#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 synchronize475.662832988"
1772747639.081 sable_ircd     #x1B[2m2026-03-05T21:53:59.080544Z#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): ERROR :Client quit
1772747639.081 sable_ircd     #x1B[2m2026-03-05T21:53:59.080710Z#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), 3))
1772747639.081 sable_ircd     #x1B[2m2026-03-05T21:53:59.080757Z#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), 3)
1772747639.081 sable_ircd     #x1B[2m2026-03-05T21:53:59.080927Z#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(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515304786595846, 99 => 817515304610836483) my clock=EventClock(1 => 817515304786595846, 99 => 817515304610836483)
1772747639.081 S -> 1: ERROR :Client quit
1772747639.081 sable_ircd     #x1B[2m2026-03-05T21:53:59.081251Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), 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:59811 (127.0.0.1:59811)
1772747639.081 sable_ircd     #x1B[2m2026-03-05T21:53:59.081384Z#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), 3) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed
1772747639.082 sable_ircd     #x1B[2m2026-03-05T21:53:59.081468Z#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
1772747639.083 sable_ircd     #x1B[2m2026-03-05T21:53:59.083035Z#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(817515312009187336)), timestamp: 1772747639, clock: EventClock(1 => 817515311996604423, 99 => 817515304610836483), target: User(UserId(Snowflake(817515303951929346))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515311996604423, 99 => 817515304610836483) my clock=EventClock(1 => 817515311996604423, 99 => 817515304610836483)
1772747639.084 sable_ircd     #x1B[2m2026-03-05T21:53:59.083295Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515312009187336)), timestamp: 1772747639, clock: EventClock(1 => 817515311996604423, 99 => 817515304610836483), target: User(UserId(Snowflake(817515303951929346))), 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:59811 (127.0.0.1:59811)
1772747639.084 sable_ircd     #x1B[2m2026-03-05T21:53:59.083407Z#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(817515311996604423)), timestamp: 1772747639, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(817515303951929347)), user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 } }), users_to_notify: [] })
1772747639.084 sable_ircd     #x1B[2m2026-03-05T21:53:59.083449Z#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(817515311996604423)), timestamp: 1772747639, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(817515303951929347)), user: UserId(Snowflake(817515303951929346)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747637 } }), users_to_notify: [] }
1772747639.084 sable_ircd     #x1B[2m2026-03-05T21:53:59.083504Z#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(817515311996604423)), timestamp: 1772747639, change: EventComplete(EventComplete), users_to_notify: [] })
1772747639.084 sable_ircd     #x1B[2m2026-03-05T21:53:59.083537Z#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(817515311996604423)), timestamp: 1772747639, change: EventComplete(EventComplete), users_to_notify: [] }
1772747639.087 sable_ircd     #x1B[2m2026-03-05T21:53:59.087178Z#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(817515312009187336)), timestamp: 1772747639, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] })
1772747639.087 sable_ircd     #x1B[2m2026-03-05T21:53:59.087225Z#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(817515312009187336)), timestamp: 1772747639, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515303951929346)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] }
1772747639.087 sable_ircd     #x1B[2m2026-03-05T21:53:59.087291Z#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(817515312009187336)), timestamp: 1772747639, change: EventComplete(EventComplete), users_to_notify: [] })
1772747639.087 sable_ircd     #x1B[2m2026-03-05T21:53:59.087330Z#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(817515312009187336)), timestamp: 1772747639, change: EventComplete(EventComplete), users_to_notify: [] }
1772747639.088 sable_services #x1B[2m2026-03-05T21:53:59.087876Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515312009187336)), timestamp: 1772747639, clock: EventClock(1 => 817515311996604423, 99 => 817515304610836483), target: User(UserId(Snowflake(817515303951929346))), 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(817515312009187336)), timestamp: 1772747639, clock: EventClock(1 => 817515311996604423, 99 => 817515304610836483), target: User(UserId(Snowflake(817515303951929346))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515311996604423, 99 => 817515304610836483) my clock=EventClock(1 => 817515304786595846, 99 => 817515304610836483)
1772747639.088 sable_services #x1B[2m2026-03-05T21:53:59.087928Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515312009187336)), timestamp: 1772747639, clock: EventClock(1 => 817515311996604423, 99 => 817515304610836483), target: User(UserId(Snowflake(817515303951929346))), 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(817515311996604423))]
1772747639.088 sable_services #x1B[2m2026-03-05T21:53:59.088190Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515312009187336)), timestamp: 1772747639, clock: EventClock(1 => 817515311996604423, 99 => 817515304610836483), target: User(UserId(Snowflake(817515303951929346))), 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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515312009187336)), timestamp: 1772747639, clock: EventClock(1 => 817515311996604423, 99 => 817515304610836483), target: User(UserId(Snowflake(817515303951929346))), 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:45613 (127.0.0.1:45613)
1772747639.124 sable_services #x1B[2m2026-03-05T21:53:59.123702Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), 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(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515304786595846, 99 => 817515304610836483) my clock=EventClock(1 => 817515304786595846, 99 => 817515304610836483)
1772747639.124 sable_services #x1B[2m2026-03-05T21:53:59.123787Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), 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(817515312009187336)), timestamp: 1772747639, clock: EventClock(1 => 817515311996604423, 99 => 817515304610836483), target: User(UserId(Snowflake(817515303951929346))), details: UserQuit(UserQuit { message: "Client Quit" }) }
1772747639.124 sable_services #x1B[2m2026-03-05T21:53:59.124125Z#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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), 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), 1772747636), content: NewEvent(Event { id: EventId(Snowflake(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), 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:45613 (127.0.0.1:45613)
1772747639.130 sable_ircd     #x1B[2m2026-03-05T21:53:59.129544Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515311996604423))]) } }#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(817515311996604423))]
1772747639.130 sable_ircd     #x1B[2m2026-03-05T21:53:59.129592Z#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), 1772747636), content: GetEvent([EventId(Snowflake(817515311996604423))]) } }#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(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), details: UserDisconnect(UserDisconnect) }]
1772747639.171 sable_services #x1B[2m2026-03-05T21:53:59.170581Z#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), 1772747636), content: BulkEvents([Event { id: EventId(Snowflake(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), 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(817515311996604423)), timestamp: 1772747639, clock: EventClock(1 => 817515304786595846, 99 => 817515304610836483), target: UserConnection(UserConnectionId(Snowflake(817515303951929347))), details: UserDisconnect(UserDisconnect) }]
1772747639.181 1 -> S: PING synchronize476.517941403
1772747639.182 1: connects to server.
1772747639.182 1 -> S: CAP REQ :sasl
1772747639.182 sable_ircd     #x1B[2m2026-03-05T21:53:59.182157Z#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 }
1772747639.182 sable_ircd     #x1B[2m2026-03-05T21:53:59.182299Z#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
1772747639.182 sable_ircd     #x1B[2m2026-03-05T21:53:59.182373Z#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
1772747639.182 sable_ircd     #x1B[2m2026-03-05T21:53:59.182430Z#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
1772747639.183 sable_ircd     #x1B[2m2026-03-05T21:53:59.182574Z#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
1772747639.183 sable_ircd     #x1B[2m2026-03-05T21:53:59.182641Z#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"CAP REQ :sasl"
1772747639.183 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747639.183 sable_ircd     #x1B[2m2026-03-05T21:53:59.182809Z#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 CAP * ACK :sasl
1772747639.183 sable_ircd     #x1B[2m2026-03-05T21:53:59.182874Z#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[0mUpdateConnectionCaps(ConnectionId(ListenerId(0), 4), ClientCapabilitySet(8))
1772747639.183 S -> 1: :My.Little.Server CAP * ACK :sasl
1772747639.183 1 -> S: AUTHENTICATE PLAIN
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.183490Z#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
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.183529Z#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"))
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.183568Z#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
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.183605Z#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: 476, tv_nsec: 519077000 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(<uninit>), realname: OnceLock(<uninit>), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747639.184 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.183838Z#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
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.183900Z#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"AUTHENTICATE PLAIN"
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.184045Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::command::handlers::services::sasl#x1B[0m#x1B[2m:#x1B[0m Beginning new SASL session  #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515312432812036))
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.184148Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Found target peer, sending message #x1B[3mtarget#x1B[0m#x1B[2m=#x1B[0mPeerConfig { name: ServerName("My.Little.Services"), address: "127.0.0.1:59811", fingerprint: "874a0844acad05a0f6e9d427b95c8b987d78ea81" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(BeginAuthenticate(SaslSessionId(Snowflake(817515312432812036)), "PLAIN")) }
1772747639.184 sable_ircd     #x1B[2m2026-03-05T21:53:59.184414Z#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), 1772747636), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(BeginAuthenticate(SaslSessionId(Snowflake(817515312432812036)), "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:59811 (127.0.0.1:59811)
1772747639.186 sable_ircd     #x1B[2m2026-03-05T21:53:59.186128Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(35) })
1772747639.227 sable_services #x1B[2m2026-03-05T21:53:59.226683Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(BeginAuthenticate(SaslSessionId(Snowflake(817515312432812036)), "PLAIN"))
1772747639.227 sable_services #x1B[2m2026-03-05T21:53:59.226734Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got begin authenticate #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515312432812036)) #x1B[3mmechanism#x1B[0m#x1B[2m=#x1B[0m"PLAIN"
1772747639.268 sable_ircd     #x1B[2m2026-03-05T21:53:59.267562Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got targeted message response #x1B[3mresponse#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747636), content: TargetedMessageResponse(Services(Authenticate(InProgress([])))) } }
1772747639.268 sable_ircd     #x1B[2m2026-03-05T21:53:59.267741Z#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): AUTHENTICATE :+
1772747639.268 sable_ircd     #x1B[2m2026-03-05T21:53:59.267813Z#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
1772747639.268 S -> 1: AUTHENTICATE :+
1772747639.268 1 -> S: AUTHENTICATE !!!
1772747639.269 sable_ircd     #x1B[2m2026-03-05T21:53:59.268549Z#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
1772747639.269 sable_ircd     #x1B[2m2026-03-05T21:53:59.268594Z#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"AUTHENTICATE !!!"
1772747639.269 sable_ircd     #x1B[2m2026-03-05T21:53:59.268715Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::command::handlers::services::sasl#x1B[0m#x1B[2m:#x1B[0m Resuming SASL session #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515312432812036))
1772747639.269 sable_ircd     #x1B[2m2026-03-05T21:53:59.268821Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Found target peer, sending message #x1B[3mtarget#x1B[0m#x1B[2m=#x1B[0mPeerConfig { name: ServerName("My.Little.Services"), address: "127.0.0.1:59811", fingerprint: "874a0844acad05a0f6e9d427b95c8b987d78ea81" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(FailAuthenticate(SaslSessionId(Snowflake(817515312432812036)))) }
1772747639.269 sable_ircd     #x1B[2m2026-03-05T21:53:59.269039Z#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), 1772747636), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(FailAuthenticate(SaslSessionId(Snowflake(817515312432812036)))) }) } #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:59811 (127.0.0.1:59811)
1772747639.271 sable_ircd     #x1B[2m2026-03-05T21:53:59.270680Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(36) })
1772747639.312 sable_services #x1B[2m2026-03-05T21:53:59.311639Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(FailAuthenticate(SaslSessionId(Snowflake(817515312432812036))))
1772747639.312 sable_services #x1B[2m2026-03-05T21:53:59.311685Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got fail authenticate #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515312432812036))
1772747639.353 sable_ircd     #x1B[2m2026-03-05T21:53:59.352546Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got targeted message response #x1B[3mresponse#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747636), content: TargetedMessageResponse(Services(Authenticate(Fail))) } }
1772747639.353 sable_ircd     #x1B[2m2026-03-05T21:53:59.352712Z#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 904 * :SASL authentication failed
1772747639.353 sable_ircd     #x1B[2m2026-03-05T21:53:59.352775Z#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
1772747639.353 S -> 1: :My.Little.Server 904 * :SASL authentication failed
1772747639.353 1 -> S: AUTHENTICATE PLAIN
1772747639.354 sable_ircd     #x1B[2m2026-03-05T21:53:59.353486Z#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
1772747639.354 sable_ircd     #x1B[2m2026-03-05T21:53:59.353546Z#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"AUTHENTICATE PLAIN"
1772747639.354 sable_ircd     #x1B[2m2026-03-05T21:53:59.353727Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::command::handlers::services::sasl#x1B[0m#x1B[2m:#x1B[0m Beginning new SASL session  #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515313141649413))
1772747639.354 sable_ircd     #x1B[2m2026-03-05T21:53:59.353857Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Found target peer, sending message #x1B[3mtarget#x1B[0m#x1B[2m=#x1B[0mPeerConfig { name: ServerName("My.Little.Services"), address: "127.0.0.1:59811", fingerprint: "874a0844acad05a0f6e9d427b95c8b987d78ea81" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(BeginAuthenticate(SaslSessionId(Snowflake(817515313141649413)), "PLAIN")) }
1772747639.354 sable_ircd     #x1B[2m2026-03-05T21:53:59.354131Z#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), 1772747636), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(BeginAuthenticate(SaslSessionId(Snowflake(817515313141649413)), "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:59811 (127.0.0.1:59811)
1772747639.356 sable_ircd     #x1B[2m2026-03-05T21:53:59.356093Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(37) })
1772747639.397 sable_services #x1B[2m2026-03-05T21:53:59.396711Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(BeginAuthenticate(SaslSessionId(Snowflake(817515313141649413)), "PLAIN"))
1772747639.397 sable_services #x1B[2m2026-03-05T21:53:59.396757Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got begin authenticate #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515313141649413)) #x1B[3mmechanism#x1B[0m#x1B[2m=#x1B[0m"PLAIN"
1772747639.438 sable_ircd     #x1B[2m2026-03-05T21:53:59.437593Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got targeted message response #x1B[3mresponse#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747636), content: TargetedMessageResponse(Services(Authenticate(InProgress([])))) } }
1772747639.438 sable_ircd     #x1B[2m2026-03-05T21:53:59.437778Z#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): AUTHENTICATE :+
1772747639.438 sable_ircd     #x1B[2m2026-03-05T21:53:59.437849Z#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
1772747639.438 S -> 1: AUTHENTICATE :+
1772747639.438 1 -> S: AUTHENTICATE amlsbGVzAGppbGxlcwBzZXNhbWU=
1772747639.439 sable_ircd     #x1B[2m2026-03-05T21:53:59.438608Z#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
1772747639.439 sable_ircd     #x1B[2m2026-03-05T21:53:59.438668Z#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"AUTHENTICATE amlsbGVzAGppbGxlcwBzZXNhbWU="
1772747640.194 sable_ircd     #x1B[2m2026-03-05T21:54:00.193514Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::command::handlers::services::sasl#x1B[0m#x1B[2m:#x1B[0m Resuming SASL session #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515313141649413))
1772747640.194 sable_ircd     #x1B[2m2026-03-05T21:54:00.193711Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Found target peer, sending message #x1B[3mtarget#x1B[0m#x1B[2m=#x1B[0mPeerConfig { name: ServerName("My.Little.Services"), address: "127.0.0.1:59811", fingerprint: "874a0844acad05a0f6e9d427b95c8b987d78ea81" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(Authenticate(SaslSessionId(Snowflake(817515313141649413)), [106, 105, 108, 108, 101, 115, 0, 106, 105, 108, 108, 101, 115, 0, 115, 101, 115, 97, 109, 101])) }
1772747640.194 sable_ircd     #x1B[2m2026-03-05T21:54:00.193986Z#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), 1772747636), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(Authenticate(SaslSessionId(Snowflake(817515313141649413)), [106, 105, 108, 108, 101, 115, 0, 106, 105, 108, 108, 101, 115, 0, 115, 101, 115, 97, 109, 101])) }) } #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:59811 (127.0.0.1:59811)
1772747640.196 sable_ircd     #x1B[2m2026-03-05T21:54:00.195783Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(38) })
1772747640.237 sable_services #x1B[2m2026-03-05T21:54:00.236923Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(Authenticate(SaslSessionId(Snowflake(817515313141649413)), [106, 105, 108, 108, 101, 115, 0, 106, 105, 108, 108, 101, 115, 0, 115, 101, 115, 97, 109, 101]))
1772747640.237 sable_services #x1B[2m2026-03-05T21:54:00.236986Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got authenticate data #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515313141649413)) #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0m[106, 105, 108, 108, 101, 115, 0, 106, 105, 108, 108, 101, 115, 0, 115, 101, 115, 97, 109, 101]
1772747640.241 sable_services #x1B[2m2026-03-05T21:54:00.240723Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server::sasl::plain#x1B[0m#x1B[2m:#x1B[0m sasl login successful #x1B[3maccount_name#x1B[0m#x1B[2m=#x1B[0mNickname("jilles")
1772747640.282 sable_ircd     #x1B[2m2026-03-05T21:54:00.281720Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got targeted message response #x1B[3mresponse#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1772747636), content: TargetedMessageResponse(Services(Authenticate(Success(AccountId(Snowflake(817515304589864960)))))) } }
1772747640.282 sable_ircd     #x1B[2m2026-03-05T21:54:00.281964Z#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 900 * * jilles :You are now logged in as jilles
1772747640.282 sable_ircd     #x1B[2m2026-03-05T21:54:00.282027Z#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 903 * :SASL authentication successful
1772747640.282 sable_ircd     #x1B[2m2026-03-05T21:54:00.282072Z#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
1772747640.282 S -> 1: :My.Little.Server 900 * * jilles :You are now logged in as jilles
1772747640.323 S -> 1: :My.Little.Server 903 * :SASL authentication successful
1772747640.326 sable_ircd     #x1B[2m2026-03-05T21:54:00.326003Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error in outbound network sync connection: I/O error: unexpected end of file
1772747640.327 sable_ircd     #x1B[2m2026-03-05T21:54:00.326251Z#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:45613, peer: 127.0.0.1:41693, fd: 19 }) } #x1B[3mmessage_sender#x1B[0m#x1B[2m=#x1B[0mUnboundedSender { chan: Tx { inner: Chan { tx: Tx { block_tail: 0x55a38f994010, 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
1772747640.327 sable_ircd     #x1B[2m2026-03-05T21:54:00.326288Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error in outbound network sync connection: I/O error: unexpected end of file
1772747640.327 sable_ircd     #x1B[2m2026-03-05T21:54:00.326288Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error in outbound network sync connection: I/O error: unexpected end of file
1772747640.327 sable_ircd     #x1B[2m2026-03-05T21:54:00.326449Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error in outbound network sync connection: I/O error: unexpected end of file
1772747640.327 sable_ircd     #x1B[2m2026-03-05T21:54:00.326451Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error in outbound network sync connection: I/O error: unexpected end of file
1772747640.329 1: disconnects from server.