--------------------------------- Captured Out ---------------------------------
---- new test ----
1772747644.532 sable_ircd     uid=1001
1772747644.532 sable_services uid=1001
1772747644.533 sable_services #x1B[2m2026-03-05T21:54:04.532973Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.History"), address: "127.0.0.1:54909", fingerprint: "4a2a95d286514b6b3bbc01ce0b75dbd9b988d16c" }
1772747644.533 sable_services #x1B[2m2026-03-05T21:54:04.533229Z#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), 1772747644), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Could not connect to 127.0.0.1:54909. Last error: I/O error: Connection refused (os error 111)
1772747644.533 sable_services #x1B[2m2026-03-05T21:54:04.533284Z#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), 1772747644), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mI/O error: Connection refused (os error 111)
1772747644.536 sable_ircd     #x1B[2m2026-03-05T21:54:04.535677Z#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(817515334876532736)), timestamp: 1772747644, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Server"), ts: 1772747644, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1772747644.536 sable_ircd     #x1B[2m2026-03-05T21:54:04.535827Z#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
1772747644.536 sable_ircd     #x1B[2m2026-03-05T21:54:04.535926Z#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(817515334876532737)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532736), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1772747644 }) }; event clock=EventClock(1 => 817515334876532736) my clock=EventClock(1 => 817515334876532736)
1772747644.536 sable_ircd     #x1B[2m2026-03-05T21:54:04.535975Z#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
1772747644.537 sable_ircd     #x1B[2m2026-03-05T21:54:04.536266Z#x1B[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
1772747644.537 sable_ircd     #x1B[2m2026-03-05T21:54:04.536732Z#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(817515334876532736)), timestamp: 1772747644, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Server"), ts: 1772747644, 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
1772747644.537 sable_ircd     #x1B[2m2026-03-05T21:54:04.536960Z#x1B[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(817515334876532736)), timestamp: 1772747644, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1772747644.537 sable_ircd     #x1B[2m2026-03-05T21:54:04.537006Z#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(817515334876532736)), timestamp: 1772747644, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1772747644.537 sable_ircd     #x1B[2m2026-03-05T21:54:04.537080Z#x1B[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(817515334876532736)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.537 sable_ircd     #x1B[2m2026-03-05T21:54:04.537117Z#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(817515334876532736)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.537 sable_ircd     #x1B[2m2026-03-05T21:54:04.537179Z#x1B[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(817515334876532737)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.537 sable_ircd     #x1B[2m2026-03-05T21:54:04.537212Z#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(817515334876532737)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.555 sable_history  #x1B[2m2027-03-05T22:32:23.545630Z#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(949796548563378221)), timestamp: 1804285943, clock: EventClock(1 => 949786721300516886, 50 => 949796296913526828, 99 => 949786724434063364), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804285943 }) }; event clock=EventClock(1 => 949786721300516886, 50 => 949796296913526828, 99 => 949786724434063364) my clock=EventClock(1 => 949786721300516886, 50 => 949796296913526828, 99 => 949786724434063364)
1772747644.555 sable_history  #x1B[2m2027-03-05T22:32:23.547450Z#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
1772747644.628 sable_ircd     #x1B[2m2026-03-05T21:54:04.627970Z#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 }
1772747644.628 sable_ircd     #x1B[2m2026-03-05T21:54:04.628145Z#x1B[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
1772747644.628 sable_ircd     #x1B[2m2026-03-05T21:54:04.628219Z#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
1772747644.628 sable_ircd     #x1B[2m2026-03-05T21:54:04.628291Z#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
1772747644.629 sable_ircd     #x1B[2m2026-03-05T21:54:04.628472Z#x1B[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
1772747644.629 sable_ircd     #x1B[2m2026-03-05T21:54:04.628532Z#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"
1772747644.629 sable_ircd     #x1B[2m2026-03-05T21:54:04.628665Z#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
1772747644.629 sable_ircd     #x1B[2m2026-03-05T21:54:04.628787Z#x1B[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))
1772747644.629 sable_ircd     #x1B[2m2026-03-05T21:54:04.628831Z#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)
1772747644.629 sable_ircd     #x1B[2m2026-03-05T21:54:04.629278Z#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
1772747644.629 sable_ircd     #x1B[2m2026-03-05T21:54:04.629385Z#x1B[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
1772747644.630 sable_ircd     #x1B[2m2026-03-05T21:54:04.629569Z#x1B[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
1772747644.633 sable_services #x1B[2m2026-03-05T21:54:04.632797Z#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:49623", fingerprint: "5264ab93242d734b90c3240e1bd2034c23e29119" }
1772747644.633 sable_services #x1B[2m2026-03-05T21:54:04.633012Z#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), 1772747644), 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:49623 (127.0.0.1:49623)
1772747644.638 sable_ircd     #x1B[2m2026-03-05T21:54:04.637866Z#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
1772747644.649 chkSvs: connects to server.
1772747644.649 chkSvs -> S: NICK chkSvs
1772747644.649 chkSvs -> S: USER chk chk chk chk
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.649592Z#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 }
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.649753Z#x1B[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
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.649796Z#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
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.649852Z#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
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.649989Z#x1B[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
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.650050Z#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"
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.650176Z#x1B[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
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.650232Z#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"
1772747644.650 sable_ircd     #x1B[2m2026-03-05T21:54:04.650372Z#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: 481, tv_nsec: 986496784 }, 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
1772747644.651 sable_ircd     #x1B[2m2026-03-05T21:54:04.650507Z#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: 481, tv_nsec: 986496784 }, 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
1772747644.651 sable_ircd     #x1B[2m2026-03-05T21:54:04.650589Z#x1B[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
1772747644.651 sable_ircd     #x1B[2m2026-03-05T21:54:04.651135Z#x1B[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
1772747644.651 sable_ircd     #x1B[2m2026-03-05T21:54:04.651179Z#x1B[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"))
1772747644.651 sable_ircd     #x1B[2m2026-03-05T21:54:04.651215Z#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
1772747644.651 sable_ircd     #x1B[2m2026-03-05T21:54:04.651239Z#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: 481, tv_nsec: 986496784 }, 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
1772747644.651 sable_ircd     #x1B[2m2026-03-05T21:54:04.651281Z#x1B[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))
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.651560Z#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(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }; event clock=EventClock(1 => 817515334876532737) my clock=EventClock(1 => 817515334876532737)
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.651691Z#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
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.651965Z#x1B[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(817515335363072002)), timestamp: 1772747644, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515335363072000)), 1) }), users_to_notify: [] })
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652003Z#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(817515335363072002)), timestamp: 1772747644, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515335363072000)), 1) }), users_to_notify: [] }
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652058Z#x1B[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(817515335363072002)), timestamp: 1772747644, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515335363072000)), 2), connection: UserConnectionId(Snowflake(817515335363072001)) }), users_to_notify: [] })
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652083Z#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(817515335363072002)), timestamp: 1772747644, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515335363072000)), 2), connection: UserConnectionId(Snowflake(817515335363072001)) }), users_to_notify: [] }
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652128Z#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
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652156Z#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
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652188Z#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:54:04.652171701 UTC
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652209Z#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
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652240Z#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
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652260Z#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
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652280Z#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 +
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652296Z#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.
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652366Z#x1B[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(817515335363072002)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.652 sable_ircd     #x1B[2m2026-03-05T21:54:04.652394Z#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(817515335363072002)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.679 sable_services #x1B[2m2026-03-05T21:54:04.679050Z#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), 1772747644), 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: 1772747644, name: ServerName("My.Little.Server"), last_ping: 1772747644, 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: {"user_param_1": (0, String(_)), "host": (3, String(_)), "ip": (0, Ip(_)), "tls": (0, Boolean(_)), "realname": (1, String(_)), "user": (5, String(_)), "user_param_2": (2, String(_)), "nick": (4, String(_))} }, new_connection_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [], uint64: [], regex: [] }, fields: {"ip": (0, Ip(_)), "tls": (0, Boolean(_))} }, pre_sasl_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [""], uint64: [], regex: [] }, fields: {"ip": (0, Ip(_)), "mechanism": (0, String(_)), "tls": (0, Boolean(_))} } }, audit_log: {}, accounts: {}, nick_registrations: {}, channel_registrations: {}, channel_accesses: {}, channel_roles: {}, current_services: None, current_history_server_id: None, config: NetworkConfig { opers: [OperConfig { name: "operuser", hash: "$6$z5yA.OfGliDoi/R2$BgSsguS6bxAsPSCygDisgDw5JZuo5.88eU3Hyc7/4OaNpeKIxWGjOggeHzOl0xLiZg1vfwxXjOTFN14wG5vNI." }], debug_mode: true, default_roles: {BuiltinOp: ChannelAccessSet(222192934775), BuiltinAll: ChannelAccessSet(1114112), BuiltinVoice: ChannelAccessSet(4296081441)}, 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 => 817515334876532737), cache_default_channel_roles: OnceLock(<uninit>), alias_users: OnceLock(<uninit>) }) }
1772747644.679 sable_services #x1B[2m2026-03-05T21:54:04.679250Z#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
1772747644.679 sable_services #x1B[2m2026-03-05T21:54:04.679406Z#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
1772747644.680 sable_services #x1B[2m2026-03-05T21:54:04.679922Z#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(817515335480913920)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747644, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 817515334876532737) my clock=EventClock(1 => 817515334876532737)
1772747644.680 sable_services #x1B[2m2026-03-05T21:54:04.680314Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335480913920)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747644, 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:49623 (127.0.0.1:49623)
1772747644.682 sable_services #x1B[2m2026-03-05T21:54:04.682113Z#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(817515335493496833)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335480913920), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747644 }) }; event clock=EventClock(1 => 817515334876532737, 99 => 817515335480913920) my clock=EventClock(1 => 817515334876532737, 99 => 817515335480913920)
1772747644.683 sable_services #x1B[2m2026-03-05T21:54:04.682763Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335493496833)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335480913920), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747644 }) }) } #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:49623 (127.0.0.1:49623)
1772747644.687 sable_services #x1B[2m2026-03-05T21:54:04.686492Z#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(817515335480913920)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747644, 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
1772747644.687 sable_services #x1B[2m2026-03-05T21:54:04.686717Z#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(817515335510274050)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335493496833), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 817515334876532737, 99 => 817515335493496833) my clock=EventClock(1 => 817515334876532737, 99 => 817515335493496833)
1772747644.687 sable_services #x1B[2m2026-03-05T21:54:04.687013Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335510274050)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335493496833), 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:49623 (127.0.0.1:49623)
1772747644.688 sable_ircd     #x1B[2m2026-03-05T21:54:04.687431Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335493496833)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335480913920), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747644 }) }) } }#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(817515335493496833)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335480913920), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747644 }) }; event clock=EventClock(1 => 817515334876532737, 99 => 817515335480913920) my clock=EventClock(1 => 817515335363072002)
1772747644.688 sable_ircd     #x1B[2m2026-03-05T21:54:04.687498Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335493496833)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335480913920), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747644 }) }) } }#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(817515334876532737)), EventId(Snowflake(817515335480913920))]
1772747644.688 sable_ircd     #x1B[2m2026-03-05T21:54:04.687531Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335493496833)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335480913920), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747644 }) }) } }#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
1772747644.724 sable_ircd     #x1B[2m2026-03-05T21:54:04.723874Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335480913920)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747644, 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(817515335480913920)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747644, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 817515334876532737) my clock=EventClock(1 => 817515335363072002)
1772747644.724 sable_ircd     #x1B[2m2026-03-05T21:54:04.723973Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335480913920)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747644, 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(817515335493496833)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335480913920), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747644 }) }
1772747644.724 sable_ircd     #x1B[2m2026-03-05T21:54:04.724028Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335480913920)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747644, 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
1772747644.724 sable_ircd     #x1B[2m2026-03-05T21:54:04.724416Z#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(817515335480913920)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747644, 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
1772747644.725 sable_ircd     #x1B[2m2026-03-05T21:54:04.724614Z#x1B[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(817515335480913920)), timestamp: 1772747644, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] })
1772747644.725 sable_ircd     #x1B[2m2026-03-05T21:54:04.724657Z#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(817515335480913920)), timestamp: 1772747644, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] }
1772747644.725 sable_ircd     #x1B[2m2026-03-05T21:54:04.724725Z#x1B[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(817515335480913920)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.725 sable_ircd     #x1B[2m2026-03-05T21:54:04.724753Z#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(817515335480913920)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.725 sable_ircd     #x1B[2m2026-03-05T21:54:04.724787Z#x1B[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(817515335493496833)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.725 sable_ircd     #x1B[2m2026-03-05T21:54:04.724805Z#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(817515335493496833)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.729 sable_services #x1B[2m2026-03-05T21:54:04.728635Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515334876532737)), EventId(Snowflake(817515335480913920))]) } }#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(817515334876532737)), EventId(Snowflake(817515335480913920))]
1772747644.729 sable_services #x1B[2m2026-03-05T21:54:04.728692Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515334876532737)), EventId(Snowflake(817515335480913920))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events []
1772747644.731 sable_ircd     #x1B[2m2026-03-05T21:54:04.730672Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335510274050)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335493496833), 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(817515335510274050)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335493496833), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 817515334876532737, 99 => 817515335493496833) my clock=EventClock(1 => 817515335363072002, 99 => 817515335493496833)
1772747644.731 sable_ircd     #x1B[2m2026-03-05T21:54:04.731080Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335510274050)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335493496833), 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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515335510274050)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737, 99 => 817515335493496833), 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:42381 (127.0.0.1:42381)
1772747644.735 sable_ircd     #x1B[2m2026-03-05T21:54:04.734898Z#x1B[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(817515335510274050)), timestamp: 1772747644, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] })
1772747644.735 sable_ircd     #x1B[2m2026-03-05T21:54:04.734949Z#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(817515335510274050)), timestamp: 1772747644, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] }
1772747644.735 sable_ircd     #x1B[2m2026-03-05T21:54:04.735079Z#x1B[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(817515335510274050)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.735 sable_ircd     #x1B[2m2026-03-05T21:54:04.735126Z#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(817515335510274050)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.749 S -> chkSvs: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747644.749 S -> chkSvs: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747644.749 S -> chkSvs: :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs
1772747644.749 S -> chkSvs: :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747644.749 S -> chkSvs: :My.Little.Server 003 chkSvs :This server was created 2026-03-05 21:54:04.652171701 UTC
1772747644.749 S -> chkSvs: :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747644.750 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
1772747644.750 S -> chkSvs: :My.Little.Server 422 chkSvs :MOTD File is missing
1772747644.750 S -> chkSvs: :chkSvs!chk@localhost MODE chkSvs +
1772747644.750 S -> chkSvs: :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747644.750 chkSvs -> S: CAP LS 302
1772747644.750 sable_ircd     #x1B[2m2026-03-05T21:54:04.749995Z#x1B[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
1772747644.750 sable_ircd     #x1B[2m2026-03-05T21:54:04.750050Z#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"
1772747644.750 sable_ircd     #x1B[2m2026-03-05T21:54:04.750169Z#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
1772747644.770 sable_ircd     #x1B[2m2026-03-05T21:54:04.769687Z#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), 1772747644), 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: []
1772747644.800 chkSvs -> S: PING synchronize482.136358901
1772747644.800 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
1772747644.800 sable_ircd     #x1B[2m2026-03-05T21:54:04.800302Z#x1B[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
1772747644.800 sable_ircd     #x1B[2m2026-03-05T21:54:04.800397Z#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 synchronize482.136358901"
1772747644.801 sable_ircd     #x1B[2m2026-03-05T21:54:04.800537Z#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 :synchronize482.136358901
1772747644.801 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize482.136358901
1772747644.801 chkSvs -> S: QUIT
1772747644.801 chkSvs -> S: PING synchronize482.137639999
1772747644.801 sable_ircd     #x1B[2m2026-03-05T21:54:04.801221Z#x1B[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
1772747644.801 sable_ircd     #x1B[2m2026-03-05T21:54:04.801264Z#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"
1772747644.801 sable_ircd     #x1B[2m2026-03-05T21:54:04.801320Z#x1B[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
1772747644.801 sable_ircd     #x1B[2m2026-03-05T21:54:04.801372Z#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 synchronize482.137639999"
1772747645.053 sable_ircd     #x1B[2m2026-03-05T21:54:05.052978Z#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
1772747645.053 sable_ircd     #x1B[2m2026-03-05T21:54:05.053120Z#x1B[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))
1772747645.053 sable_ircd     #x1B[2m2026-03-05T21:54:05.053160Z#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)
1772747645.053 sable_ircd     #x1B[2m2026-03-05T21:54:05.053292Z#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(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515335363072002, 99 => 817515335510274050) my clock=EventClock(1 => 817515335363072002, 99 => 817515335510274050)
1772747645.054 S -> chkSvs: ERROR :Client quit
1772747645.054 chkSvs: disconnects from server.
1772747645.054 1: connects to server.
1772747645.054 1 -> S: NICK jilles
1772747645.054 1 -> S: USER r e g :user
1772747645.054 sable_ircd     #x1B[2m2026-03-05T21:54:05.053589Z#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
1772747645.054 sable_ircd     #x1B[2m2026-03-05T21:54:05.053662Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), 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:42381 (127.0.0.1:42381)
1772747645.054 sable_ircd     #x1B[2m2026-03-05T21:54:05.053685Z#x1B[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
1772747645.054 sable_ircd     #x1B[2m2026-03-05T21:54:05.054179Z#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 }
1772747645.054 sable_ircd     #x1B[2m2026-03-05T21:54:05.054306Z#x1B[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
1772747645.054 sable_ircd     #x1B[2m2026-03-05T21:54:05.054383Z#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
1772747645.054 sable_ircd     #x1B[2m2026-03-05T21:54:05.054449Z#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
1772747645.055 sable_ircd     #x1B[2m2026-03-05T21:54:05.054618Z#x1B[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
1772747645.055 sable_ircd     #x1B[2m2026-03-05T21:54:05.054675Z#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"
1772747645.055 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747645.055 sable_ircd     #x1B[2m2026-03-05T21:54:05.054826Z#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: 482, tv_nsec: 391095082 }, 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
1772747645.055 sable_ircd     #x1B[2m2026-03-05T21:54:05.054893Z#x1B[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
1772747645.055 sable_ircd     #x1B[2m2026-03-05T21:54:05.054951Z#x1B[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
1772747645.055 sable_ircd     #x1B[2m2026-03-05T21:54:05.055001Z#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"
1772747645.055 sable_ircd     #x1B[2m2026-03-05T21:54:05.055090Z#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: 482, tv_nsec: 391095082 }, 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
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.055723Z#x1B[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
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.055762Z#x1B[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"))
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.055810Z#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
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.055852Z#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: 482, tv_nsec: 391095082 }, 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
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.055862Z#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(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515337049182211, 99 => 817515335510274050) my clock=EventClock(1 => 817515337049182211, 99 => 817515335510274050)
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.055909Z#x1B[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))
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.056117Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), 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:42381 (127.0.0.1:42381)
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.056246Z#x1B[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(817515337049182211)), timestamp: 1772747645, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515335363072000)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(817515335363072001)), user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 } }), users_to_notify: [] })
1772747645.056 sable_ircd     #x1B[2m2026-03-05T21:54:05.056324Z#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(817515337049182211)), timestamp: 1772747645, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515335363072000)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(817515335363072001)), user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 } }), users_to_notify: [] }
1772747645.056 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747645.057 sable_ircd     #x1B[2m2026-03-05T21:54:05.056455Z#x1B[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(817515337049182211)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] })
1772747645.057 sable_ircd     #x1B[2m2026-03-05T21:54:05.056501Z#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(817515337049182211)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] }
1772747645.060 sable_ircd     #x1B[2m2026-03-05T21:54:05.059957Z#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(817515337074348037)), timestamp: 1772747645, clock: EventClock(1 => 817515337057570820, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), 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(817515337061765123)), NewUserConnection { user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 })) }) }; event clock=EventClock(1 => 817515337057570820, 99 => 817515335510274050) my clock=EventClock(1 => 817515337057570820, 99 => 817515335510274050)
1772747645.061 sable_ircd     #x1B[2m2026-03-05T21:54:05.060236Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337074348037)), timestamp: 1772747645, clock: EventClock(1 => 817515337057570820, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), 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(817515337061765123)), NewUserConnection { user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 })) }) }) } #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:42381 (127.0.0.1:42381)
1772747645.061 sable_ircd     #x1B[2m2026-03-05T21:54:05.060405Z#x1B[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(817515337057570820)), timestamp: 1772747645, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515335363072000)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] })
1772747645.061 sable_ircd     #x1B[2m2026-03-05T21:54:05.060443Z#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(817515337057570820)), timestamp: 1772747645, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515335363072000)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] }
1772747645.061 sable_ircd     #x1B[2m2026-03-05T21:54:05.060501Z#x1B[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(817515337057570820)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] })
1772747645.061 sable_ircd     #x1B[2m2026-03-05T21:54:05.060531Z#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(817515337057570820)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] }
1772747645.061 sable_services #x1B[2m2026-03-05T21:54:05.061000Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), 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(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515337049182211, 99 => 817515335510274050) my clock=EventClock(1 => 817515334876532737, 99 => 817515335510274050)
1772747645.061 sable_services #x1B[2m2026-03-05T21:54:05.061096Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), 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(817515337049182211))]
1772747645.062 sable_services #x1B[2m2026-03-05T21:54:05.061459Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), 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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), 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:49623 (127.0.0.1:49623)
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062655Z#x1B[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(817515337074348037)), timestamp: 1772747645, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 1) }), users_to_notify: [] })
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062698Z#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(817515337074348037)), timestamp: 1772747645, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 1) }), users_to_notify: [] }
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062760Z#x1B[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(817515337074348037)), timestamp: 1772747645, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 2), connection: UserConnectionId(Snowflake(817515337061765123)) }), users_to_notify: [] })
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062796Z#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(817515337074348037)), timestamp: 1772747645, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 2), connection: UserConnectionId(Snowflake(817515337061765123)) }), users_to_notify: [] }
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062853Z#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
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062892Z#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
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062930Z#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:54:05.062916238 UTC
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062966Z#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
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.062987Z#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
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.063006Z#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
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.063022Z#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 +
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.063035Z#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.
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.063087Z#x1B[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(817515337074348037)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] })
1772747645.063 sable_ircd     #x1B[2m2026-03-05T21:54:05.063116Z#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(817515337074348037)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] }
1772747645.063 S -> 1: :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles
1772747645.097 sable_services #x1B[2m2026-03-05T21:54:05.096693Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), 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(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515335363072002, 99 => 817515335510274050) my clock=EventClock(1 => 817515334876532737, 99 => 817515335510274050)
1772747645.097 sable_services #x1B[2m2026-03-05T21:54:05.096750Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), 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(817515335363072002))]
1772747645.097 sable_services #x1B[2m2026-03-05T21:54:05.097062Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), 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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), 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:49623 (127.0.0.1:49623)
1772747645.103 sable_ircd     #x1B[2m2026-03-05T21:54:05.102527Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515337049182211))]) } }#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(817515337049182211))]
1772747645.103 sable_ircd     #x1B[2m2026-03-05T21:54:05.102568Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515337049182211))]) } }#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(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), details: UserDisconnect(UserDisconnect) }]
1772747645.105 sable_services #x1B[2m2026-03-05T21:54:05.104594Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337074348037)), timestamp: 1772747645, clock: EventClock(1 => 817515337057570820, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), 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(817515337061765123)), NewUserConnection { user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 })) }) }) } }#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(817515337074348037)), timestamp: 1772747645, clock: EventClock(1 => 817515337057570820, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), 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(817515337061765123)), NewUserConnection { user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 })) }) }; event clock=EventClock(1 => 817515337057570820, 99 => 817515335510274050) my clock=EventClock(1 => 817515334876532737, 99 => 817515335510274050)
1772747645.105 sable_services #x1B[2m2026-03-05T21:54:05.104656Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337074348037)), timestamp: 1772747645, clock: EventClock(1 => 817515337057570820, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), 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(817515337061765123)), NewUserConnection { user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 })) }) }) } }#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(817515337057570820))]
1772747645.105 sable_services #x1B[2m2026-03-05T21:54:05.104953Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337074348037)), timestamp: 1772747645, clock: EventClock(1 => 817515337057570820, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), 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(817515337061765123)), NewUserConnection { user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 })) }) }) } }#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337074348037)), timestamp: 1772747645, clock: EventClock(1 => 817515337057570820, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), 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(817515337061765123)), NewUserConnection { user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 })) }) }) } #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:49623 (127.0.0.1:49623)
1772747645.138 sable_ircd     #x1B[2m2026-03-05T21:54:05.137729Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515335363072002))]) } }#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(817515335363072002))]
1772747645.138 sable_ircd     #x1B[2m2026-03-05T21:54:05.137805Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515335363072002))]) } }#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(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]
1772747645.144 sable_services #x1B[2m2026-03-05T21:54:05.143694Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), 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(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), details: UserDisconnect(UserDisconnect) }]
1772747645.144 sable_services #x1B[2m2026-03-05T21:54:05.143803Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), 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(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515335363072002, 99 => 817515335510274050) my clock=EventClock(1 => 817515334876532737, 99 => 817515335510274050)
1772747645.144 sable_services #x1B[2m2026-03-05T21:54:05.143867Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), 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(817515335363072002))]
1772747645.146 sable_ircd     #x1B[2m2026-03-05T21:54:05.145549Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515337057570820))]) } }#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(817515337057570820))]
1772747645.146 sable_ircd     #x1B[2m2026-03-05T21:54:05.145586Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515337057570820))]) } }#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(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), details: UserQuit(UserQuit { message: "Client Quit" }) }]
1772747645.164 1 -> S: PING synchronize482.50018965
1772747645.164 S -> 1: :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747645.164 S -> 1: :My.Little.Server 003 jilles :This server was created 2026-03-05 21:54:05.062916238 UTC
1772747645.164 S -> 1: :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747645.164 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
1772747645.164 S -> 1: :My.Little.Server 422 jilles :MOTD File is missing
1772747645.164 S -> 1: :jilles!r@localhost MODE jilles +
1772747645.164 S -> 1: :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747645.164 sable_ircd     #x1B[2m2026-03-05T21:54:05.163967Z#x1B[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
1772747645.164 sable_ircd     #x1B[2m2026-03-05T21:54:05.164038Z#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 synchronize482.50018965"
1772747645.164 sable_ircd     #x1B[2m2026-03-05T21:54:05.164189Z#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 :synchronize482.50018965
1772747645.165 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize482.50018965
1772747645.165 1 -> S: REGISTER * * sesame
1772747645.165 sable_ircd     #x1B[2m2026-03-05T21:54:05.164820Z#x1B[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
1772747645.165 sable_ircd     #x1B[2m2026-03-05T21:54:05.164872Z#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"
1772747645.165 sable_ircd     #x1B[2m2026-03-05T21:54:05.165094Z#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:42381", fingerprint: "c65af79824ec74b717c0d7315280c77db543439a" } #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")) }
1772747645.165 sable_ircd     #x1B[2m2026-03-05T21:54:05.165366Z#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), 1772747644), 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:42381 (127.0.0.1:42381)
1772747645.167 sable_ircd     #x1B[2m2026-03-05T21:54:05.167009Z#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) })
1772747645.179 sable_services #x1B[2m2026-03-05T21:54:05.178708Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]) } }#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(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]
1772747645.179 sable_services #x1B[2m2026-03-05T21:54:05.178808Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]) } }#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(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }; event clock=EventClock(1 => 817515334876532737) my clock=EventClock(1 => 817515334876532737, 99 => 817515335510274050)
1772747645.179 sable_services #x1B[2m2026-03-05T21:54:05.178899Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]) } }#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(817515337049182211)), timestamp: 1772747645, clock: EventClock(1 => 817515335363072002, 99 => 817515335510274050), target: UserConnection(UserConnectionId(Snowflake(817515335363072001))), details: UserDisconnect(UserDisconnect) }
1772747645.179 sable_services #x1B[2m2026-03-05T21:54:05.178954Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]) } }#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(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), details: UserQuit(UserQuit { message: "Client Quit" }) }
1772747645.179 sable_services #x1B[2m2026-03-05T21:54:05.179008Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]) } }#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(817515337074348037)), timestamp: 1772747645, clock: EventClock(1 => 817515337057570820, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), 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(817515337061765123)), NewUserConnection { user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 })) }) }
1772747645.187 sable_services #x1B[2m2026-03-05T21:54:05.186619Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), 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(817515337057570820)), timestamp: 1772747645, clock: EventClock(1 => 817515337049182211, 99 => 817515335510274050), target: User(UserId(Snowflake(817515335363072000))), details: UserQuit(UserQuit { message: "Client Quit" }) }]
1772747645.208 sable_services #x1B[2m2026-03-05T21:54:05.207599Z#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"))
1772747645.208 sable_services #x1B[2m2026-03-05T21:54:05.207630Z#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")
1772747645.212 sable_services #x1B[2m2026-03-05T21:54:05.211489Z#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(817515337695506432)), name: Nickname("jilles"), authorised_fingerprints: [] }
1772747645.212 sable_services #x1B[2m2026-03-05T21:54:05.211661Z#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(817515337712283651)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: Account(AccountId(Snowflake(817515337695506432))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515337695506432)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 817515337074348037, 99 => 817515335510274050) my clock=EventClock(1 => 817515337074348037, 99 => 817515335510274050)
1772747645.212 sable_services #x1B[2m2026-03-05T21:54:05.211947Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337712283651)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: Account(AccountId(Snowflake(817515337695506432))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515337695506432)), 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:49623 (127.0.0.1:49623)
1772747645.253 sable_ircd     #x1B[2m2026-03-05T21:54:05.252627Z#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), 1772747644), content: TargetedMessageResponse(Services(LogUserIn(AccountId(Snowflake(817515337695506432))))) } }
1772747645.253 sable_ircd     #x1B[2m2026-03-05T21:54:05.252826Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515335363072002))]) } }#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(817515335363072002))]
1772747645.253 sable_ircd     #x1B[2m2026-03-05T21:54:05.252880Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515335363072002))]) } }#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(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]
1772747645.253 sable_ircd     #x1B[2m2026-03-05T21:54:05.253105Z#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
1772747645.253 sable_ircd     #x1B[2m2026-03-05T21:54:05.253161Z#x1B[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
1772747645.253 sable_ircd     #x1B[2m2026-03-05T21:54:05.253205Z#x1B[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(817515337061765122))), UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515337695506432))) }))
1772747645.253 sable_ircd     #x1B[2m2026-03-05T21:54:05.253392Z#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(817515337888043014)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515337695506432))) }) }; event clock=EventClock(1 => 817515337074348037, 99 => 817515335510274050) my clock=EventClock(1 => 817515337074348037, 99 => 817515335510274050)
1772747645.254 sable_ircd     #x1B[2m2026-03-05T21:54:05.253732Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337888043014)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515337695506432))) }) }) } #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:42381 (127.0.0.1:42381)
1772747645.256 sable_ircd     #x1B[2m2026-03-05T21:54:05.255561Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337712283651)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: Account(AccountId(Snowflake(817515337695506432))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515337695506432)), 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(817515337712283651)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: Account(AccountId(Snowflake(817515337695506432))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515337695506432)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 817515337074348037, 99 => 817515335510274050) my clock=EventClock(1 => 817515337888043014, 99 => 817515335510274050)
1772747645.256 sable_ircd     #x1B[2m2026-03-05T21:54:05.255904Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337712283651)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: Account(AccountId(Snowflake(817515337695506432))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515337695506432)), 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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337712283651)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: Account(AccountId(Snowflake(817515337695506432))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515337695506432)), 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:42381 (127.0.0.1:42381)
1772747645.256 sable_ircd     #x1B[2m2026-03-05T21:54:05.256002Z#x1B[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(817515337888043014)), timestamp: 1772747645, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 3), old_account: None, new_account: Some(AccountId(Snowflake(817515337695506432))) }), users_to_notify: [] })
1772747645.256 sable_ircd     #x1B[2m2026-03-05T21:54:05.256045Z#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(817515337888043014)), timestamp: 1772747645, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 3), old_account: None, new_account: Some(AccountId(Snowflake(817515337695506432))) }), users_to_notify: [] }
1772747645.256 sable_ircd     #x1B[2m2026-03-05T21:54:05.256095Z#x1B[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(817515337888043014)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] })
1772747645.256 sable_ircd     #x1B[2m2026-03-05T21:54:05.256112Z#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(817515337888043014)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] }
1772747645.258 sable_ircd     #x1B[2m2026-03-05T21:54:05.257844Z#x1B[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(817515337712283651)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] })
1772747645.258 sable_ircd     #x1B[2m2026-03-05T21:54:05.257883Z#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(817515337712283651)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] }
1772747645.295 sable_services #x1B[2m2026-03-05T21:54:05.294644Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]) } }#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(817515335363072002)), timestamp: 1772747644, clock: EventClock(1 => 817515334876532737), target: User(UserId(Snowflake(817515335363072000))), 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(817515335363072001)), NewUserConnection { user: UserId(Snowflake(817515335363072000)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }]
1772747645.297 sable_services #x1B[2m2026-03-05T21:54:05.296563Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337888043014)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515337695506432))) }) }) } }#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(817515337888043014)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515337695506432))) }) }; event clock=EventClock(1 => 817515337074348037, 99 => 817515335510274050) my clock=EventClock(1 => 817515337074348037, 99 => 817515337712283651)
1772747645.297 sable_services #x1B[2m2026-03-05T21:54:05.296809Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337888043014)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515337695506432))) }) }) } }#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515337888043014)), timestamp: 1772747645, clock: EventClock(1 => 817515337074348037, 99 => 817515335510274050), target: User(UserId(Snowflake(817515337061765122))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515337695506432))) }) }) } #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:49623 (127.0.0.1:49623)
1772747645.365 1 -> S: PING synchronize482.701428722
1772747645.365 S -> 1: REGISTER SUCCESS jilles :You have successfully registered
1772747645.365 sable_ircd     #x1B[2m2026-03-05T21:54:05.365305Z#x1B[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
1772747645.365 sable_ircd     #x1B[2m2026-03-05T21:54:05.365398Z#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 synchronize482.701428722"
1772747645.485 sable_history  #x1B[2m2027-03-05T22:35:12.460292Z#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(949797257044238384)), timestamp: 1804286112, clock: EventClock(1 => 949786674810851350, 50 => 949797005385998383, 99 => 949786678212833284), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804286112 }) }; event clock=EventClock(1 => 949786674810851350, 50 => 949797005385998383, 99 => 949786678212833284) my clock=EventClock(1 => 949786674810851350, 50 => 949797005385998383, 99 => 949786678212833284)
1772747645.485 sable_history  #x1B[2m2027-03-05T22:35:12.462006Z#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
1772747645.852 sable_history  #x1B[2m2027-03-05T22:24:56.661198Z#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(949794674196029477)), timestamp: 1804285496, clock: EventClock(1 => 949787112654245921, 50 => 949794422537789476, 99 => 949787117096415237), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804285496 }) }; event clock=EventClock(1 => 949787112654245921, 50 => 949794422537789476, 99 => 949787117096415237) my clock=EventClock(1 => 949787112654245921, 50 => 949794422537789476, 99 => 949787117096415237)
1772747645.852 sable_history  #x1B[2m2027-03-05T22:24:56.663185Z#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
1772747646.110 sable_history  #x1B[2m2027-03-05T22:38:01.007753Z#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(949797963985788979)), timestamp: 1804286281, clock: EventClock(1 => 949786627163557910, 50 => 949797712331743282, 99 => 949786630502625284), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804286281 }) }; event clock=EventClock(1 => 949786627163557910, 50 => 949797712331743282, 99 => 949786630502625284) my clock=EventClock(1 => 949786627163557910, 50 => 949797712331743282, 99 => 949786630502625284)
1772747646.110 sable_history  #x1B[2m2027-03-05T22:38:01.009338Z#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
1772747646.119 sable_ircd     #x1B[2m2026-03-05T21:54:06.119043Z#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 :synchronize482.701428722
1772747646.119 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize482.701428722
1772747646.120 1 -> S: QUIT
1772747646.120 sable_ircd     #x1B[2m2026-03-05T21:54:06.119754Z#x1B[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
1772747646.120 sable_ircd     #x1B[2m2026-03-05T21:54:06.119812Z#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"
1772747646.211 sable_history  #x1B[2m2027-03-05T22:22:11.038475Z#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(949793979522818081)), timestamp: 1804285331, clock: EventClock(1 => 949786416022294531, 50 => 949793727860383776), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804285331 }) }; event clock=EventClock(1 => 949786416022294531, 50 => 949793727860383776) my clock=EventClock(1 => 949786416022294531, 50 => 949793727860383776)
1772747646.211 sable_history  #x1B[2m2027-03-05T22:22:11.039951Z#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
1772747646.220 1 -> S: PING synchronize483.556276911
1772747646.220 sable_ircd     #x1B[2m2026-03-05T21:54:06.219984Z#x1B[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
1772747646.220 sable_ircd     #x1B[2m2026-03-05T21:54:06.220048Z#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 synchronize483.556276911"
1772747646.649 sable_history  #x1B[2m2027-03-05T22:22:09.562127Z#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(949793973332025377)), timestamp: 1804285329, clock: EventClock(1 => 949786411253370881, 50 => 949793721669591072), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804285329 }) }; event clock=EventClock(1 => 949786411253370881, 50 => 949793721669591072) my clock=EventClock(1 => 949786411253370881, 50 => 949793721669591072)
1772747646.649 sable_history  #x1B[2m2027-03-05T22:22:09.563879Z#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
1772747646.728 sable_history  #x1B[2m2027-03-05T22:40:58.431032Z#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(949798708151787574)), timestamp: 1804286458, clock: EventClock(1 => 949786576752218129, 50 => 949798456493547573, 99 => 949786583388008453), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804286458 }) }; event clock=EventClock(1 => 949786576752218129, 50 => 949798456493547573, 99 => 949786583388008453) my clock=EventClock(1 => 949786576752218129, 50 => 949798456493547573, 99 => 949786583388008453)
1772747646.728 sable_history  #x1B[2m2027-03-05T22:40:58.432266Z#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
1772747646.893 sable_history  #x1B[2m2027-03-05T22:27:45.799016Z#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(949795383612219432)), timestamp: 1804285665, clock: EventClock(1 => 949786815001268246, 50 => 949795131953979431, 99 => 949786818277421060), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804285665 }) }; event clock=EventClock(1 => 949786815001268246, 50 => 949795131953979431, 99 => 949786818277421060) my clock=EventClock(1 => 949786815001268246, 50 => 949795131953979431, 99 => 949786818277421060)
1772747646.893 sable_history  #x1B[2m2027-03-05T22:27:45.800159Z#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
1772747647.097 sable_history  #x1B[2m2027-03-05T22:22:09.969095Z#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(949793975039107105)), timestamp: 1804285329, clock: EventClock(1 => 949786413140807681, 50 => 949793723380867104), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804285329 }) }; event clock=EventClock(1 => 949786413140807681, 50 => 949793723380867104) my clock=EventClock(1 => 949786413140807681, 50 => 949793723380867104)
1772747647.097 sable_history  #x1B[2m2027-03-05T22:22:09.971001Z#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
1772747647.220 1: waiting…
1772747647.225 sable_ircd     #x1B[2m2026-03-05T21:54:07.225389Z#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
1772747647.226 sable_ircd     #x1B[2m2026-03-05T21:54:07.225528Z#x1B[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))
1772747647.226 sable_ircd     #x1B[2m2026-03-05T21:54:07.225564Z#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)
1772747647.226 sable_ircd     #x1B[2m2026-03-05T21:54:07.225701Z#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(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515337888043014, 99 => 817515337712283651) my clock=EventClock(1 => 817515337888043014, 99 => 817515337712283651)
1772747647.226 S -> 1: ERROR :Client quit
1772747647.226 sable_ircd     #x1B[2m2026-03-05T21:54:07.226026Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), 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:42381 (127.0.0.1:42381)
1772747647.226 sable_ircd     #x1B[2m2026-03-05T21:54:07.226121Z#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
1772747647.226 sable_ircd     #x1B[2m2026-03-05T21:54:07.226211Z#x1B[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
1772747647.229 sable_ircd     #x1B[2m2026-03-05T21:54:07.228444Z#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(817515346171793416)), timestamp: 1772747647, clock: EventClock(1 => 817515346159210503, 99 => 817515337712283651), target: User(UserId(Snowflake(817515337061765122))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515346159210503, 99 => 817515337712283651) my clock=EventClock(1 => 817515346159210503, 99 => 817515337712283651)
1772747647.229 sable_ircd     #x1B[2m2026-03-05T21:54:07.228861Z#x1B[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(817515346159210503)), timestamp: 1772747647, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(817515337061765123)), user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 } }), users_to_notify: [] })
1772747647.229 sable_ircd     #x1B[2m2026-03-05T21:54:07.228911Z#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(817515346159210503)), timestamp: 1772747647, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(817515337061765123)), user: UserId(Snowflake(817515337061765122)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747645 } }), users_to_notify: [] }
1772747647.229 sable_ircd     #x1B[2m2026-03-05T21:54:07.228957Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346171793416)), timestamp: 1772747647, clock: EventClock(1 => 817515346159210503, 99 => 817515337712283651), target: User(UserId(Snowflake(817515337061765122))), 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:42381 (127.0.0.1:42381)
1772747647.229 sable_ircd     #x1B[2m2026-03-05T21:54:07.229000Z#x1B[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(817515346159210503)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] })
1772747647.229 sable_ircd     #x1B[2m2026-03-05T21:54:07.229035Z#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(817515346159210503)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] }
1772747647.233 sable_ircd     #x1B[2m2026-03-05T21:54:07.232786Z#x1B[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(817515346171793416)), timestamp: 1772747647, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] })
1772747647.233 sable_ircd     #x1B[2m2026-03-05T21:54:07.232830Z#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(817515346171793416)), timestamp: 1772747647, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515337061765122)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] }
1772747647.233 sable_ircd     #x1B[2m2026-03-05T21:54:07.232896Z#x1B[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(817515346171793416)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] })
1772747647.233 sable_ircd     #x1B[2m2026-03-05T21:54:07.232931Z#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(817515346171793416)), timestamp: 1772747647, change: EventComplete(EventComplete), users_to_notify: [] }
1772747647.234 sable_services #x1B[2m2026-03-05T21:54:07.233518Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346171793416)), timestamp: 1772747647, clock: EventClock(1 => 817515346159210503, 99 => 817515337712283651), target: User(UserId(Snowflake(817515337061765122))), 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(817515346171793416)), timestamp: 1772747647, clock: EventClock(1 => 817515346159210503, 99 => 817515337712283651), target: User(UserId(Snowflake(817515337061765122))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515346159210503, 99 => 817515337712283651) my clock=EventClock(1 => 817515337888043014, 99 => 817515337712283651)
1772747647.234 sable_services #x1B[2m2026-03-05T21:54:07.233569Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346171793416)), timestamp: 1772747647, clock: EventClock(1 => 817515346159210503, 99 => 817515337712283651), target: User(UserId(Snowflake(817515337061765122))), 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(817515346159210503))]
1772747647.234 sable_services #x1B[2m2026-03-05T21:54:07.233843Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346171793416)), timestamp: 1772747647, clock: EventClock(1 => 817515346159210503, 99 => 817515337712283651), target: User(UserId(Snowflake(817515337061765122))), 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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346171793416)), timestamp: 1772747647, clock: EventClock(1 => 817515346159210503, 99 => 817515337712283651), target: User(UserId(Snowflake(817515337061765122))), 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:49623 (127.0.0.1:49623)
1772747647.270 sable_services #x1B[2m2026-03-05T21:54:07.269626Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), 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(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515337888043014, 99 => 817515337712283651) my clock=EventClock(1 => 817515337888043014, 99 => 817515337712283651)
1772747647.270 sable_services #x1B[2m2026-03-05T21:54:07.269685Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), 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(817515346171793416)), timestamp: 1772747647, clock: EventClock(1 => 817515346159210503, 99 => 817515337712283651), target: User(UserId(Snowflake(817515337061765122))), details: UserQuit(UserQuit { message: "Client Quit" }) }
1772747647.270 sable_services #x1B[2m2026-03-05T21:54:07.269942Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), 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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), 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:49623 (127.0.0.1:49623)
1772747647.276 sable_ircd     #x1B[2m2026-03-05T21:54:07.275541Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515346159210503))]) } }#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(817515346159210503))]
1772747647.276 sable_ircd     #x1B[2m2026-03-05T21:54:07.275582Z#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), 1772747644), content: GetEvent([EventId(Snowflake(817515346159210503))]) } }#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(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), details: UserDisconnect(UserDisconnect) }]
1772747647.317 sable_services #x1B[2m2026-03-05T21:54:07.316627Z#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), 1772747644), content: BulkEvents([Event { id: EventId(Snowflake(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), 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(817515346159210503)), timestamp: 1772747647, clock: EventClock(1 => 817515337888043014, 99 => 817515337712283651), target: UserConnection(UserConnectionId(Snowflake(817515337061765123))), details: UserDisconnect(UserDisconnect) }]
1772747647.326 1 -> S: PING synchronize484.662671532
1772747647.327 1: connects to server.
1772747647.327 1 -> S: CAP LS 302
1772747647.327 sable_ircd     #x1B[2m2026-03-05T21:54:07.327030Z#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 }
1772747647.327 sable_ircd     #x1B[2m2026-03-05T21:54:07.327159Z#x1B[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
1772747647.327 sable_ircd     #x1B[2m2026-03-05T21:54:07.327201Z#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
1772747647.327 sable_ircd     #x1B[2m2026-03-05T21:54:07.327262Z#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
1772747647.327 sable_ircd     #x1B[2m2026-03-05T21:54:07.327421Z#x1B[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
1772747647.328 sable_ircd     #x1B[2m2026-03-05T21:54:07.327478Z#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 LS 302"
1772747647.328 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747647.328 sable_ircd     #x1B[2m2026-03-05T21:54:07.327601Z#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 * 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
1772747647.328 S -> 1: :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
1772747647.328 1 -> S: AUTHENTICATE PLAIN
1772747647.328 1 -> S: NICK foo
1772747647.328 1 -> S: USER foo 0 * :foo
1772747647.328 1 -> S: CAP END
1772747647.328 sable_ircd     #x1B[2m2026-03-05T21:54:07.328239Z#x1B[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
1772747647.328 sable_ircd     #x1B[2m2026-03-05T21:54:07.328271Z#x1B[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"))
1772747647.328 sable_ircd     #x1B[2m2026-03-05T21:54:07.328299Z#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
1772747647.328 sable_ircd     #x1B[2m2026-03-05T21:54:07.328323Z#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: 484, tv_nsec: 663904446 }, 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
1772747647.329 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.328604Z#x1B[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
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.328648Z#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"
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.328773Z#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(817515346591223812))
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.328824Z#x1B[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
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.328852Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK foo"
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.328945Z#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: 484, tv_nsec: 663904446 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("foo")), realname: OnceLock(<uninit>), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: <locked> }, 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
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.328992Z#x1B[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
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.329019Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER foo 0 * :foo"
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.329082Z#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: 484, tv_nsec: 663904446 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("foo")), extra_user_params: OnceLock(("0", "*")), nick: OnceLock(Nickname("foo")), realname: OnceLock(Realname("foo")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: <locked> }, 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
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.329132Z#x1B[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
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.329160Z#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 END"
1772747647.329 sable_ircd     #x1B[2m2026-03-05T21:54:07.329272Z#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:42381", fingerprint: "c65af79824ec74b717c0d7315280c77db543439a" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(BeginAuthenticate(SaslSessionId(Snowflake(817515346591223812)), "PLAIN")) }
1772747647.330 sable_ircd     #x1B[2m2026-03-05T21:54:07.329512Z#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), 1772747644), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(BeginAuthenticate(SaslSessionId(Snowflake(817515346591223812)), "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:42381 (127.0.0.1:42381)
1772747647.331 sable_ircd     #x1B[2m2026-03-05T21:54:07.331242Z#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) })
1772747647.373 sable_services #x1B[2m2026-03-05T21:54:07.372729Z#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(817515346591223812)), "PLAIN"))
1772747647.373 sable_services #x1B[2m2026-03-05T21:54:07.372789Z#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(817515346591223812)) #x1B[3mmechanism#x1B[0m#x1B[2m=#x1B[0m"PLAIN"
1772747647.414 sable_ircd     #x1B[2m2026-03-05T21:54:07.413587Z#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), 1772747644), content: TargetedMessageResponse(Services(Authenticate(InProgress([])))) } }
1772747647.414 sable_ircd     #x1B[2m2026-03-05T21:54:07.413740Z#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 :+
1772747647.414 sable_ircd     #x1B[2m2026-03-05T21:54:07.413789Z#x1B[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
1772747647.414 S -> 1: AUTHENTICATE :+
Did not get RPL_WELCOME, waiting...
1772747647.540 sable_history  #x1B[2m2027-03-05T22:30:34.401200Z#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(949796090780262443)), timestamp: 1804285834, clock: EventClock(1 => 949786767345586198, 50 => 949795839126216746, 99 => 949786770697236484), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804285834 }) }; event clock=EventClock(1 => 949786767345586198, 50 => 949795839126216746, 99 => 949786770697236484) my clock=EventClock(1 => 949786767345586198, 50 => 949795839126216746, 99 => 949786770697236484)
1772747647.540 sable_history  #x1B[2m2027-03-05T22:30:34.403094Z#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
1772747647.546 sable_history  #x1B[2m2027-03-05T22:22:10.386329Z#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(949793976788131873)), timestamp: 1804285330, clock: EventClock(1 => 949786421634273283, 50 => 949793725134086176), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1804285330 }) }; event clock=EventClock(1 => 949786421634273283, 50 => 949793725134086176) my clock=EventClock(1 => 949786421634273283, 50 => 949793725134086176)
1772747647.546 sable_history  #x1B[2m2027-03-05T22:22:10.387994Z#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
1772747648.167 sable_ircd     #x1B[2m2026-03-05T21:54:08.166672Z#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::complete_progress #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 484, tv_nsec: 663904446 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("foo")), extra_user_params: OnceLock(("0", "*")), nick: OnceLock(Nickname("foo")), realname: OnceLock(Realname("foo")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: Some(SaslSessionId(Snowflake(817515346591223812))) }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mflag#x1B[0m#x1B[2m=#x1B[0mCapNegotiation #x1B[3mresult#x1B[0m#x1B[2m=#x1B[0mtrue
1772747648.167 sable_ircd     #x1B[2m2026-03-05T21:54:08.166796Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mRegisterClient(ConnectionId(ListenerId(0), 4))
1772747648.167 sable_ircd     #x1B[2m2026-03-05T21:54:08.167022Z#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(817515350106050569)), timestamp: 1772747648, clock: EventClock(1 => 817515346171793416, 99 => 817515337712283651), target: User(UserId(Snowflake(817515350106050565))), details: NewUser(NewUser { nickname: Nickname("foo"), username: Username("foo"), visible_hostname: Hostname("localhost"), realname: Realname("foo"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515350106050566)), NewUserConnection { user: UserId(Snowflake(817515350106050565)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747648 })) }) }; event clock=EventClock(1 => 817515346171793416, 99 => 817515337712283651) my clock=EventClock(1 => 817515346171793416, 99 => 817515337712283651)
1772747648.167 sable_ircd     #x1B[2m2026-03-05T21:54:08.167382Z#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), 1772747644), content: NewEvent(Event { id: EventId(Snowflake(817515350106050569)), timestamp: 1772747648, clock: EventClock(1 => 817515346171793416, 99 => 817515337712283651), target: User(UserId(Snowflake(817515350106050565))), details: NewUser(NewUser { nickname: Nickname("foo"), username: Username("foo"), visible_hostname: Hostname("localhost"), realname: Realname("foo"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(817515350106050566)), NewUserConnection { user: UserId(Snowflake(817515350106050565)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747648 })) }) }) } #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:42381 (127.0.0.1:42381)
1772747648.169 sable_ircd     #x1B[2m2026-03-05T21:54:08.169411Z#x1B[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(817515350106050569)), timestamp: 1772747648, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515350106050565)), 1) }), users_to_notify: [] })
1772747648.169 sable_ircd     #x1B[2m2026-03-05T21:54:08.169459Z#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(817515350106050569)), timestamp: 1772747648, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515350106050565)), 1) }), users_to_notify: [] }
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169532Z#x1B[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(817515350106050569)), timestamp: 1772747648, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515350106050565)), 2), connection: UserConnectionId(Snowflake(817515350106050566)) }), users_to_notify: [] })
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169572Z#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(817515350106050569)), timestamp: 1772747648, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515350106050565)), 2), connection: UserConnectionId(Snowflake(817515350106050566)) }), users_to_notify: [] }
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169636Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169681Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169714Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 003 foo :This server was created 2026-03-05 21:54:08.169701601 UTC
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169752Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169798Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 005 foo EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169838Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 422 foo :MOTD File is missing
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169861Z#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): :foo!foo@localhost MODE foo +
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169874Z#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 foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169920Z#x1B[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(817515350106050569)), timestamp: 1772747648, change: EventComplete(EventComplete), users_to_notify: [] })
1772747648.170 sable_ircd     #x1B[2m2026-03-05T21:54:08.169944Z#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(817515350106050569)), timestamp: 1772747648, change: EventComplete(EventComplete), users_to_notify: [] }
1772747648.170 S -> 1: :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo
1772747648.170 S -> 1: :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747648.173 sable_ircd     #x1B[2m2026-03-05T21:54:08.172585Z#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
1772747648.173 sable_ircd     #x1B[2m2026-03-05T21:54:08.172785Z#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
1772747648.173 sable_ircd     #x1B[2m2026-03-05T21:54:08.172785Z#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:49623, peer: 127.0.0.1:43889, fd: 19 }) } #x1B[3mmessage_sender#x1B[0m#x1B[2m=#x1B[0mUnboundedSender { chan: Tx { inner: Chan { tx: Tx { block_tail: 0x55b15d857b10, 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
1772747648.173 sable_ircd     #x1B[2m2026-03-05T21:54:08.172931Z#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
1772747648.178 1: disconnects from server.