--------------------------------- Captured Out ---------------------------------
---- new test ----
1772747644.458 sable_ircd     uid=1001
1772747644.458 sable_services uid=1001
1772747644.459 sable_services #x1B[2m2026-03-05T21:54:04.459044Z#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:37459", fingerprint: "4b0000b6c55d48783c9a2908d6559ad9d6fb8c19" }
1772747644.459 sable_services #x1B[2m2026-03-05T21:54:04.459306Z#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.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Could not connect to 127.0.0.1:37459. Last error: I/O error: Connection refused (os error 111)
1772747644.459 sable_services #x1B[2m2026-03-05T21:54:04.459401Z#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.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mI/O error: Connection refused (os error 111)
1772747644.462 sable_ircd     #x1B[2m2026-03-05T21:54:04.461633Z#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(817515334566154240)), 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.462 sable_ircd     #x1B[2m2026-03-05T21:54:04.461757Z#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.462 sable_ircd     #x1B[2m2026-03-05T21:54:04.461992Z#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(817515334566154240)), 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.462 sable_ircd     #x1B[2m2026-03-05T21:54:04.462094Z#x1B[0m #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(817515334566154240)), timestamp: 1772747644, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1772747644.462 sable_ircd     #x1B[2m2026-03-05T21:54:04.462140Z#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(817515334566154240)), timestamp: 1772747644, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1772747644.462 sable_ircd     #x1B[2m2026-03-05T21:54:04.462210Z#x1B[0m #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(817515334566154240)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.462 sable_ircd     #x1B[2m2026-03-05T21:54:04.462251Z#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(817515334566154240)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.463 sable_ircd     #x1B[2m2026-03-05T21:54:04.462578Z#x1B[0m #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.463 sable_ircd     #x1B[2m2026-03-05T21:54:04.462894Z#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(817515334570348545)), timestamp: 1772747644, clock: EventClock(1 => 817515334566154240), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1772747644 }) }; event clock=EventClock(1 => 817515334566154240) my clock=EventClock(1 => 817515334566154240)
1772747644.463 sable_ircd     #x1B[2m2026-03-05T21:54:04.462960Z#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.463 sable_ircd     #x1B[2m2026-03-05T21:54:04.463178Z#x1B[0m #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(817515334570348545)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.463 sable_ircd     #x1B[2m2026-03-05T21:54:04.463210Z#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(817515334570348545)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.554 sable_ircd     #x1B[2m2026-03-05T21:54:04.554158Z#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.554 sable_ircd     #x1B[2m2026-03-05T21:54:04.554398Z#x1B[0m #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.555 sable_ircd     #x1B[2m2026-03-05T21:54:04.554466Z#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.555 sable_ircd     #x1B[2m2026-03-05T21:54:04.554566Z#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.555 sable_ircd     #x1B[2m2026-03-05T21:54:04.554754Z#x1B[0m #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.555 sable_ircd     #x1B[2m2026-03-05T21:54:04.554820Z#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.555 sable_ircd     #x1B[2m2026-03-05T21:54:04.554951Z#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.555 sable_ircd     #x1B[2m2026-03-05T21:54:04.555069Z#x1B[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.555 sable_ircd     #x1B[2m2026-03-05T21:54:04.555107Z#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.556 sable_ircd     #x1B[2m2026-03-05T21:54:04.555618Z#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.556 sable_ircd     #x1B[2m2026-03-05T21:54:04.555714Z#x1B[0m #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.556 sable_ircd     #x1B[2m2026-03-05T21:54:04.555898Z#x1B[0m #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.576 chkSvs: connects to server.
1772747644.576 chkSvs -> S: NICK chkSvs
1772747644.576 chkSvs -> S: USER chk chk chk chk
1772747644.576 sable_ircd     #x1B[2m2026-03-05T21:54:04.576175Z#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.576 sable_ircd     #x1B[2m2026-03-05T21:54:04.576385Z#x1B[0m #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.576 sable_ircd     #x1B[2m2026-03-05T21:54:04.576435Z#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.577 sable_ircd     #x1B[2m2026-03-05T21:54:04.576493Z#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.577 sable_ircd     #x1B[2m2026-03-05T21:54:04.576618Z#x1B[0m #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.577 sable_ircd     #x1B[2m2026-03-05T21:54:04.576673Z#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.577 sable_ircd     #x1B[2m2026-03-05T21:54:04.576846Z#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: 913138823 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("chkSvs")), 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
1772747644.577 sable_ircd     #x1B[2m2026-03-05T21:54:04.576912Z#x1B[0m #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.577 sable_ircd     #x1B[2m2026-03-05T21:54:04.576944Z#x1B[0m #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.577 sable_ircd     #x1B[2m2026-03-05T21:54:04.576973Z#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.577 sable_ircd     #x1B[2m2026-03-05T21:54:04.577041Z#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: 913138823 }, 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.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.577556Z#x1B[0m #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.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.577599Z#x1B[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.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.577631Z#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.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.577651Z#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: 913138823 }, 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.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.577694Z#x1B[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.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.577927Z#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(817515335052693506)), timestamp: 1772747644, clock: EventClock(1 => 817515334570348545), target: User(UserId(Snowflake(817515335052693504))), 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(817515335052693505)), NewUserConnection { user: UserId(Snowflake(817515335052693504)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 })) }) }; event clock=EventClock(1 => 817515334570348545) my clock=EventClock(1 => 817515334570348545)
1772747644.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.578037Z#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.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.578357Z#x1B[0m #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(817515335052693506)), timestamp: 1772747644, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515335052693504)), 1) }), users_to_notify: [] })
1772747644.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.578400Z#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(817515335052693506)), timestamp: 1772747644, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515335052693504)), 1) }), users_to_notify: [] }
1772747644.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.578440Z#x1B[0m #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(817515335052693506)), timestamp: 1772747644, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515335052693504)), 2), connection: UserConnectionId(Snowflake(817515335052693505)) }), users_to_notify: [] })
1772747644.578 sable_ircd     #x1B[2m2026-03-05T21:54:04.578462Z#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(817515335052693506)), timestamp: 1772747644, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515335052693504)), 2), connection: UserConnectionId(Snowflake(817515335052693505)) }), users_to_notify: [] }
1772747644.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578505Z#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.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578530Z#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.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578553Z#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.578544960 UTC
1772747644.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578576Z#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.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578608Z#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.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578629Z#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.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578648Z#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.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578663Z#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.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578688Z#x1B[0m #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(817515335052693506)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] })
1772747644.579 sable_ircd     #x1B[2m2026-03-05T21:54:04.578708Z#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(817515335052693506)), timestamp: 1772747644, change: EventComplete(EventComplete), users_to_notify: [] }
1772747644.603 sable_services #x1B[2m2026-03-05T21:54:04.602992Z#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:59259", fingerprint: "8ac7c7f12826adb2513fad91d2ffa7d7904a47bb" }
1772747644.603 sable_services #x1B[2m2026-03-05T21:54:04.603290Z#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:59259. Last error: I/O error: Connection refused (os error 111)
1772747644.603 sable_services #x1B[2m2026-03-05T21:54:04.603382Z#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.676 S -> chkSvs: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747644.676 S -> chkSvs: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747644.676 S -> chkSvs: :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs
1772747644.676 S -> chkSvs: :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747644.676 S -> chkSvs: :My.Little.Server 003 chkSvs :This server was created 2026-03-05 21:54:04.578544960 UTC
1772747644.676 S -> chkSvs: :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747644.676 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.676 S -> chkSvs: :My.Little.Server 422 chkSvs :MOTD File is missing
1772747644.676 S -> chkSvs: :chkSvs!chk@localhost MODE chkSvs +
1772747644.676 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.676 chkSvs -> S: CAP LS 302
1772747644.677 sable_ircd     #x1B[2m2026-03-05T21:54:04.676703Z#x1B[0m #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.677 sable_ircd     #x1B[2m2026-03-05T21:54:04.676771Z#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.677 sable_ircd     #x1B[2m2026-03-05T21:54:04.676914Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server CAP * LS :message-tags server-time echo-message batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename
1772747644.688 sable_services #x1B[2m2026-03-05T21:54:04.687864Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.History"), address: "127.0.0.1:59259", fingerprint: "8ac7c7f12826adb2513fad91d2ffa7d7904a47bb" } (attempt #3).
1772747644.688 sable_services #x1B[2m2026-03-05T21:54:04.687905Z#x1B[0m #x1B[33m WARN#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Make sure at least one node in your network is started and reachable. If this is the first (or only) node, you must provide the --bootstrap-network option.
1772747644.688 sable_services #x1B[2m2026-03-05T21:54:04.688073Z#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:59259. Last error: I/O error: Connection refused (os error 111)
1772747644.688 sable_services #x1B[2m2026-03-05T21:54:04.688112Z#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.726 chkSvs -> S: PING synchronize482.062917345
1772747644.726 S -> chkSvs: :My.Little.Server CAP * LS :message-tags server-time echo-message batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename
1772747644.727 sable_ircd     #x1B[2m2026-03-05T21:54:04.726756Z#x1B[0m #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.727 sable_ircd     #x1B[2m2026-03-05T21:54:04.726812Z#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.062917345"
1772747644.727 sable_ircd     #x1B[2m2026-03-05T21:54:04.726930Z#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.062917345
1772747644.727 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize482.062917345
1772747644.727 chkSvs -> S: CAP LS 302
1772747644.728 sable_ircd     #x1B[2m2026-03-05T21:54:04.727557Z#x1B[0m #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.728 sable_ircd     #x1B[2m2026-03-05T21:54:04.727600Z#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.777 chkSvs -> S: PING synchronize482.114026701
1772747644.778 sable_ircd     #x1B[2m2026-03-05T21:54:04.777803Z#x1B[0m #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.778 sable_ircd     #x1B[2m2026-03-05T21:54:04.777861Z#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.114026701"
1772747644.990 sable_services #x1B[2m2026-03-05T21:54:04.989889Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.Server"), address: "127.0.0.1:37459", fingerprint: "4b0000b6c55d48783c9a2908d6559ad9d6fb8c19" } (attempt #4).
1772747644.990 sable_services #x1B[2m2026-03-05T21:54:04.990217Z#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:37459 (127.0.0.1:37459)
1772747644.995 sable_ircd     #x1B[2m2026-03-05T21:54:04.995411Z#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
1772747645.029 sable_ircd     #x1B[2m2026-03-05T21:54:05.028825Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server CAP * LS :message-tags server-time echo-message batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename
1772747645.029 S -> chkSvs: :My.Little.Server CAP * LS :message-tags server-time echo-message batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename
1772747645.037 sable_services #x1B[2m2026-03-05T21:54:05.037081Z#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: {Nickname("chkSvs"): NickBinding { nick: Nickname("chkSvs"), user: UserId(Snowflake(817515335052693504)), timestamp: 1772747644, created: EventId(Snowflake(817515335052693506)) }}, historic_nick_users: HistoricNickStore { data: {} }, users: {UserId(Snowflake(817515335052693504)): User { id: UserId(Snowflake(817515335052693504)), serial: 2, user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, mode: UserMode { modes: UserModeSet(0) }, oper_privileges: None, account: None, session_key: None }}, historic_users: HistoricUserStore { users: {HistoricUserId(UserId(Snowflake(817515335052693504)), 2): HistoricUser { id: UserId(Snowflake(817515335052693504)), serial: 2, nickname: Nickname("chkSvs"), user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, account: None, timestamp: None }, HistoricUserId(UserId(Snowflake(817515335052693504)), 1): HistoricUser { id: UserId(Snowflake(817515335052693504)), serial: 1, nickname: Nickname("chkSvs"), user: Username("chk"), visible_host: Hostname("localhost"), realname: Realname("chk"), away_reason: None, account: None, timestamp: Some(1772747644) }} }, user_connections: {UserConnectionId(Snowflake(817515335052693505)): UserConnection { id: UserConnectionId(Snowflake(817515335052693505)), user: UserId(Snowflake(817515335052693504)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 }}, 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: {"host": (4, String(_)), "realname": (1, String(_)), "user_param_2": (0, String(_)), "ip": (0, Ip(_)), "tls": (0, Boolean(_)), "nick": (2, String(_)), "user_param_1": (5, String(_)), "user": (3, 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: {"tls": (0, Boolean(_)), "mechanism": (0, String(_)), "ip": (0, Ip(_))} } }, audit_log: {}, accounts: {}, nick_registrations: {}, channel_registrations: {}, channel_accesses: {}, channel_roles: {}, current_services: None, current_history_server_id: None, config: NetworkConfig { opers: [OperConfig { name: "operuser", hash: "$6$z5yA.OfGliDoi/R2$BgSsguS6bxAsPSCygDisgDw5JZuo5.88eU3Hyc7/4OaNpeKIxWGjOggeHzOl0xLiZg1vfwxXjOTFN14wG5vNI." }], debug_mode: true, default_roles: {BuiltinAll: ChannelAccessSet(1114112), BuiltinOp: ChannelAccessSet(222192934775), 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 => 817515335052693506), cache_default_channel_roles: OnceLock(<uninit>), alias_users: OnceLock(<uninit>) }) }
1772747645.037 sable_services #x1B[2m2026-03-05T21:54:05.037281Z#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
1772747645.037 sable_services #x1B[2m2026-03-05T21:54:05.037422Z#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
1772747645.038 sable_services #x1B[2m2026-03-05T21:54:05.037935Z#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(817515336982474752)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747645, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 817515335052693506) my clock=EventClock(1 => 817515335052693506)
1772747645.038 sable_services #x1B[2m2026-03-05T21:54:05.038251Z#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(817515336982474752)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747645, 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:37459 (127.0.0.1:37459)
1772747645.040 sable_services #x1B[2m2026-03-05T21:54:05.040210Z#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(817515336995057665)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336982474752), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747645 }) }; event clock=EventClock(1 => 817515335052693506, 99 => 817515336982474752) my clock=EventClock(1 => 817515335052693506, 99 => 817515336982474752)
1772747645.041 sable_services #x1B[2m2026-03-05T21:54:05.040615Z#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(817515336995057665)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336982474752), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 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:37459 (127.0.0.1:37459)
1772747645.041 sable_services #x1B[2m2026-03-05T21:54:05.040624Z#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(817515336982474752)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747645, 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
1772747645.044 sable_services #x1B[2m2026-03-05T21:54:05.044254Z#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(817515337011834882)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336995057665), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 817515335052693506, 99 => 817515336995057665) my clock=EventClock(1 => 817515335052693506, 99 => 817515336995057665)
1772747645.045 sable_services #x1B[2m2026-03-05T21:54:05.044528Z#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(817515337011834882)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336995057665), 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:37459 (127.0.0.1:37459)
1772747645.045 sable_ircd     #x1B[2m2026-03-05T21:54:05.045215Z#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(817515336995057665)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336982474752), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 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(817515336995057665)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336982474752), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747645 }) }; event clock=EventClock(1 => 817515335052693506, 99 => 817515336982474752) my clock=EventClock(1 => 817515335052693506)
1772747645.045 sable_ircd     #x1B[2m2026-03-05T21:54:05.045300Z#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(817515336995057665)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336982474752), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 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(817515336982474752))]
1772747645.045 sable_ircd     #x1B[2m2026-03-05T21:54:05.045378Z#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(817515336995057665)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336982474752), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 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::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message
1772747645.082 sable_ircd     #x1B[2m2026-03-05T21:54:05.081782Z#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(817515336982474752)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747645, 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(817515336982474752)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747645, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 817515335052693506) my clock=EventClock(1 => 817515335052693506)
1772747645.082 sable_ircd     #x1B[2m2026-03-05T21:54:05.081884Z#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(817515336982474752)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747645, 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(817515336995057665)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336982474752), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1772747645 }) }
1772747645.082 sable_ircd     #x1B[2m2026-03-05T21:54:05.081974Z#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(817515336982474752)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747645, 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
1772747645.082 sable_ircd     #x1B[2m2026-03-05T21:54:05.082254Z#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(817515336982474752)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1772747644, name: ServerName("My.Little.Services"), ts: 1772747645, 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
1772747645.083 sable_ircd     #x1B[2m2026-03-05T21:54:05.082484Z#x1B[0m #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(817515336982474752)), timestamp: 1772747645, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] })
1772747645.083 sable_ircd     #x1B[2m2026-03-05T21:54:05.082531Z#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(817515336982474752)), timestamp: 1772747645, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] }
1772747645.083 sable_ircd     #x1B[2m2026-03-05T21:54:05.082614Z#x1B[0m #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(817515336982474752)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] })
1772747645.083 sable_ircd     #x1B[2m2026-03-05T21:54:05.082655Z#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(817515336982474752)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] }
1772747645.083 sable_ircd     #x1B[2m2026-03-05T21:54:05.082719Z#x1B[0m #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(817515336995057665)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] })
1772747645.083 sable_ircd     #x1B[2m2026-03-05T21:54:05.082745Z#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(817515336995057665)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] }
1772747645.087 sable_services #x1B[2m2026-03-05T21:54:05.086568Z#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(817515336982474752))]) } }#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(817515336982474752))]
1772747645.087 sable_services #x1B[2m2026-03-05T21:54:05.086611Z#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(817515336982474752))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events []
1772747645.088 sable_ircd     #x1B[2m2026-03-05T21:54:05.087637Z#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(817515337011834882)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336995057665), 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(817515337011834882)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336995057665), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 817515335052693506, 99 => 817515336995057665) my clock=EventClock(1 => 817515335052693506, 99 => 817515336995057665)
1772747645.088 sable_ircd     #x1B[2m2026-03-05T21:54:05.088012Z#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(817515337011834882)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336995057665), 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(817515337011834882)), timestamp: 1772747645, clock: EventClock(1 => 817515335052693506, 99 => 817515336995057665), 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:59237 (127.0.0.1:59237)
1772747645.092 sable_ircd     #x1B[2m2026-03-05T21:54:05.091698Z#x1B[0m #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(817515337011834882)), timestamp: 1772747645, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] })
1772747645.092 sable_ircd     #x1B[2m2026-03-05T21:54:05.091739Z#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(817515337011834882)), timestamp: 1772747645, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] }
1772747645.092 sable_ircd     #x1B[2m2026-03-05T21:54:05.091852Z#x1B[0m #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(817515337011834882)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] })
1772747645.092 sable_ircd     #x1B[2m2026-03-05T21:54:05.091895Z#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(817515337011834882)), timestamp: 1772747645, change: EventComplete(EventComplete), users_to_notify: [] }
1772747645.128 sable_ircd     #x1B[2m2026-03-05T21:54:05.127657Z#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: []
1772747646.030 chkSvs: waiting…
1772747646.096 sable_ircd     #x1B[2m2026-03-05T21:54:06.096096Z#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.114026701
1772747646.097 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize482.114026701
1772747646.097 chkSvs -> S: CAP LS 302
1772747646.097 sable_ircd     #x1B[2m2026-03-05T21:54:06.096928Z#x1B[0m #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.097 sable_ircd     #x1B[2m2026-03-05T21:54:06.096997Z#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"
1772747646.147 chkSvs -> S: PING synchronize483.48338146
1772747646.148 sable_ircd     #x1B[2m2026-03-05T21:54:06.147396Z#x1B[0m #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.148 sable_ircd     #x1B[2m2026-03-05T21:54:06.147467Z#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 synchronize483.48338146"
1772747647.148 chkSvs: waiting…
1772747647.153 sable_ircd     #x1B[2m2026-03-05T21:54:07.152787Z#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
1772747647.153 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
1772747648.154 chkSvs: waiting…
1772747648.158 sable_ircd     #x1B[2m2026-03-05T21:54:08.158310Z#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 :synchronize483.48338146
1772747648.159 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize483.48338146
1772747648.159 chkSvs -> S: QUIT
1772747648.159 chkSvs -> S: PING synchronize485.495619155
1772747648.159 sable_ircd     #x1B[2m2026-03-05T21:54:08.159212Z#x1B[0m #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
1772747648.159 sable_ircd     #x1B[2m2026-03-05T21:54:08.159257Z#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"
1772747648.159 sable_ircd     #x1B[2m2026-03-05T21:54:08.159315Z#x1B[0m #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
1772747648.159 sable_ircd     #x1B[2m2026-03-05T21:54:08.159373Z#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 synchronize485.495619155"
1772747649.160 chkSvs: waiting…
1772747649.164 sable_ircd     #x1B[2m2026-03-05T21:54:09.163655Z#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
1772747649.164 sable_ircd     #x1B[2m2026-03-05T21:54:09.163839Z#x1B[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))
1772747649.164 sable_ircd     #x1B[2m2026-03-05T21:54:09.163896Z#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)
1772747649.164 sable_ircd     #x1B[2m2026-03-05T21:54:09.164057Z#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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515335052693506, 99 => 817515337011834882) my clock=EventClock(1 => 817515335052693506, 99 => 817515337011834882)
1772747649.164 S -> chkSvs: ERROR :Client quit
1772747649.164 chkSvs: disconnects from server.
1772747649.165 1: connects to server.
1772747649.165 1 -> S: NICK jilles
1772747649.165 sable_ircd     #x1B[2m2026-03-05T21:54:09.164368Z#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
1772747649.165 sable_ircd     #x1B[2m2026-03-05T21:54:09.164453Z#x1B[0m #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
1772747649.165 sable_ircd     #x1B[2m2026-03-05T21:54:09.164472Z#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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), 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:59237 (127.0.0.1:59237)
1772747649.165 sable_ircd     #x1B[2m2026-03-05T21:54:09.164889Z#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 }
1772747649.165 sable_ircd     #x1B[2m2026-03-05T21:54:09.165045Z#x1B[0m #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
1772747649.165 sable_ircd     #x1B[2m2026-03-05T21:54:09.165101Z#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
1772747649.165 1 -> S: USER r e g :user
1772747649.165 sable_ircd     #x1B[2m2026-03-05T21:54:09.165163Z#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
1772747649.165 sable_ircd     #x1B[2m2026-03-05T21:54:09.165317Z#x1B[0m #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
1772747649.166 sable_ircd     #x1B[2m2026-03-05T21:54:09.165451Z#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"
1772747649.166 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747649.166 sable_ircd     #x1B[2m2026-03-05T21:54:09.165580Z#x1B[0m #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
1772747649.166 sable_ircd     #x1B[2m2026-03-05T21:54:09.165629Z#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"
1772747649.166 sable_ircd     #x1B[2m2026-03-05T21:54:09.165707Z#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: 486, tv_nsec: 501810181 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(<uninit>), 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
1772747649.166 sable_ircd     #x1B[2m2026-03-05T21:54:09.165794Z#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: 486, tv_nsec: 501810181 }, 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
1772747649.166 sable_ircd     #x1B[2m2026-03-05T21:54:09.165845Z#x1B[0m #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
1772747649.167 sable_ircd     #x1B[2m2026-03-05T21:54:09.167032Z#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(817515354304548868)), timestamp: 1772747649, clock: EventClock(1 => 817515354291965955, 99 => 817515337011834882), target: User(UserId(Snowflake(817515335052693504))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515354291965955, 99 => 817515337011834882) my clock=EventClock(1 => 817515354291965955, 99 => 817515337011834882)
1772747649.168 sable_ircd     #x1B[2m2026-03-05T21:54:09.167789Z#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(817515354304548868)), timestamp: 1772747649, clock: EventClock(1 => 817515354291965955, 99 => 817515337011834882), target: User(UserId(Snowflake(817515335052693504))), 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:59237 (127.0.0.1:59237)
1772747649.168 sable_ircd     #x1B[2m2026-03-05T21:54:09.167911Z#x1B[0m #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
1772747649.168 sable_ircd     #x1B[2m2026-03-05T21:54:09.167946Z#x1B[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"))
1772747649.168 sable_ircd     #x1B[2m2026-03-05T21:54:09.167982Z#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
1772747649.168 sable_ircd     #x1B[2m2026-03-05T21:54:09.168013Z#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: 486, tv_nsec: 501810181 }, 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
1772747649.168 sable_ircd     #x1B[2m2026-03-05T21:54:09.168072Z#x1B[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))
1772747649.168 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747649.172 sable_ircd     #x1B[2m2026-03-05T21:54:09.171671Z#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(817515354321326085)), timestamp: 1772747649, clock: EventClock(1 => 817515354304548868, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), 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(817515354308743171)), NewUserConnection { user: UserId(Snowflake(817515354308743170)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747649 })) }) }; event clock=EventClock(1 => 817515354304548868, 99 => 817515337011834882) my clock=EventClock(1 => 817515354304548868, 99 => 817515337011834882)
1772747649.172 sable_ircd     #x1B[2m2026-03-05T21:54:09.171976Z#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(817515354321326085)), timestamp: 1772747649, clock: EventClock(1 => 817515354304548868, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), 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(817515354308743171)), NewUserConnection { user: UserId(Snowflake(817515354308743170)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747649 })) }) }) } #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:59237 (127.0.0.1:59237)
1772747649.172 sable_ircd     #x1B[2m2026-03-05T21:54:09.172235Z#x1B[0m #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(817515354291965955)), timestamp: 1772747649, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515335052693504)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(817515335052693505)), user: UserId(Snowflake(817515335052693504)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 } }), users_to_notify: [] })
1772747649.172 sable_ircd     #x1B[2m2026-03-05T21:54:09.172276Z#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(817515354291965955)), timestamp: 1772747649, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515335052693504)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(817515335052693505)), user: UserId(Snowflake(817515335052693504)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747644 } }), users_to_notify: [] }
1772747649.172 sable_ircd     #x1B[2m2026-03-05T21:54:09.172330Z#x1B[0m #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(817515354291965955)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] })
1772747649.173 sable_ircd     #x1B[2m2026-03-05T21:54:09.172386Z#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(817515354291965955)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] }
1772747649.173 sable_ircd     #x1B[2m2026-03-05T21:54:09.172434Z#x1B[0m #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(817515354304548868)), timestamp: 1772747649, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515335052693504)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] })
1772747649.173 sable_ircd     #x1B[2m2026-03-05T21:54:09.172470Z#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(817515354304548868)), timestamp: 1772747649, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515335052693504)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] }
1772747649.173 sable_ircd     #x1B[2m2026-03-05T21:54:09.172553Z#x1B[0m #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(817515354304548868)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] })
1772747649.173 sable_ircd     #x1B[2m2026-03-05T21:54:09.172599Z#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(817515354304548868)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] }
1772747649.173 sable_services #x1B[2m2026-03-05T21:54:09.172719Z#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(817515354304548868)), timestamp: 1772747649, clock: EventClock(1 => 817515354291965955, 99 => 817515337011834882), target: User(UserId(Snowflake(817515335052693504))), 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(817515354304548868)), timestamp: 1772747649, clock: EventClock(1 => 817515354291965955, 99 => 817515337011834882), target: User(UserId(Snowflake(817515335052693504))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515354291965955, 99 => 817515337011834882) my clock=EventClock(1 => 817515335052693506, 99 => 817515337011834882)
1772747649.173 sable_services #x1B[2m2026-03-05T21:54:09.172809Z#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(817515354304548868)), timestamp: 1772747649, clock: EventClock(1 => 817515354291965955, 99 => 817515337011834882), target: User(UserId(Snowflake(817515335052693504))), 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(817515354291965955))]
1772747649.173 sable_services #x1B[2m2026-03-05T21:54:09.173170Z#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(817515354304548868)), timestamp: 1772747649, clock: EventClock(1 => 817515354291965955, 99 => 817515337011834882), target: User(UserId(Snowflake(817515335052693504))), 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(817515354304548868)), timestamp: 1772747649, clock: EventClock(1 => 817515354291965955, 99 => 817515337011834882), target: User(UserId(Snowflake(817515335052693504))), 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:37459 (127.0.0.1:37459)
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174368Z#x1B[0m #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(817515354321326085)), timestamp: 1772747649, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 1) }), users_to_notify: [] })
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174416Z#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(817515354321326085)), timestamp: 1772747649, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 1) }), users_to_notify: [] }
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174493Z#x1B[0m #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(817515354321326085)), timestamp: 1772747649, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 2), connection: UserConnectionId(Snowflake(817515354308743171)) }), users_to_notify: [] })
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174531Z#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(817515354321326085)), timestamp: 1772747649, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 2), connection: UserConnectionId(Snowflake(817515354308743171)) }), users_to_notify: [] }
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174603Z#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
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174652Z#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
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174689Z#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:09.174675365 UTC
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174732Z#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
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174787Z#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
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174826Z#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
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174870Z#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 +
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174909Z#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.
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.174979Z#x1B[0m #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(817515354321326085)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] })
1772747649.175 sable_ircd     #x1B[2m2026-03-05T21:54:09.175017Z#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(817515354321326085)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] }
1772747649.175 S -> 1: :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles
1772747649.175 S -> 1: :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747649.175 S -> 1: :My.Little.Server 003 jilles :This server was created 2026-03-05 21:54:09.174675365 UTC
1772747649.209 sable_services #x1B[2m2026-03-05T21:54:09.208851Z#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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), 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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515335052693506, 99 => 817515337011834882) my clock=EventClock(1 => 817515335052693506, 99 => 817515337011834882)
1772747649.209 sable_services #x1B[2m2026-03-05T21:54:09.208955Z#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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), 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(817515354304548868)), timestamp: 1772747649, clock: EventClock(1 => 817515354291965955, 99 => 817515337011834882), target: User(UserId(Snowflake(817515335052693504))), details: UserQuit(UserQuit { message: "Client Quit" }) }
1772747649.209 sable_services #x1B[2m2026-03-05T21:54:09.209264Z#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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), 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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), 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:37459 (127.0.0.1:37459)
1772747649.214 sable_ircd     #x1B[2m2026-03-05T21:54:09.213614Z#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(817515354291965955))]) } }#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(817515354291965955))]
1772747649.214 sable_ircd     #x1B[2m2026-03-05T21:54:09.213675Z#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(817515354291965955))]) } }#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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), details: UserDisconnect(UserDisconnect) }]
1772747649.215 sable_services #x1B[2m2026-03-05T21:54:09.214694Z#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(817515354321326085)), timestamp: 1772747649, clock: EventClock(1 => 817515354304548868, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), 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(817515354308743171)), NewUserConnection { user: UserId(Snowflake(817515354308743170)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747649 })) }) }) } }#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(817515354321326085)), timestamp: 1772747649, clock: EventClock(1 => 817515354304548868, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), 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(817515354308743171)), NewUserConnection { user: UserId(Snowflake(817515354308743170)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747649 })) }) }; event clock=EventClock(1 => 817515354304548868, 99 => 817515337011834882) my clock=EventClock(1 => 817515354304548868, 99 => 817515337011834882)
1772747649.216 sable_services #x1B[2m2026-03-05T21:54:09.215050Z#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(817515354321326085)), timestamp: 1772747649, clock: EventClock(1 => 817515354304548868, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), 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(817515354308743171)), NewUserConnection { user: UserId(Snowflake(817515354308743170)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747649 })) }) }) } }#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(817515354321326085)), timestamp: 1772747649, clock: EventClock(1 => 817515354304548868, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), 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(817515354308743171)), NewUserConnection { user: UserId(Snowflake(817515354308743170)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747649 })) }) }) } #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:37459 (127.0.0.1:37459)
1772747649.255 sable_services #x1B[2m2026-03-05T21:54:09.254682Z#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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), 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(817515354291965955)), timestamp: 1772747649, clock: EventClock(1 => 817515335052693506, 99 => 817515337011834882), target: UserConnection(UserConnectionId(Snowflake(817515335052693505))), details: UserDisconnect(UserDisconnect) }]
1772747649.276 1 -> S: PING synchronize486.612238378
1772747649.276 S -> 1: :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747649.276 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
1772747649.276 S -> 1: :My.Little.Server 422 jilles :MOTD File is missing
1772747649.276 S -> 1: :jilles!r@localhost MODE jilles +
1772747649.276 S -> 1: :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747649.276 sable_ircd     #x1B[2m2026-03-05T21:54:09.276157Z#x1B[0m #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
1772747649.276 sable_ircd     #x1B[2m2026-03-05T21:54:09.276224Z#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 synchronize486.612238378"
1772747649.276 sable_ircd     #x1B[2m2026-03-05T21:54:09.276368Z#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 :synchronize486.612238378
1772747649.277 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize486.612238378
1772747649.277 1 -> S: REGISTER * * sesame
1772747649.277 sable_ircd     #x1B[2m2026-03-05T21:54:09.276972Z#x1B[0m #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
1772747649.277 sable_ircd     #x1B[2m2026-03-05T21:54:09.277014Z#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"
1772747649.277 sable_ircd     #x1B[2m2026-03-05T21:54:09.277215Z#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:59237", fingerprint: "09e66d97e33282529120b5647bb789d72174ca49" } #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")) }
1772747649.278 sable_ircd     #x1B[2m2026-03-05T21:54:09.277468Z#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:59237 (127.0.0.1:59237)
1772747649.279 sable_ircd     #x1B[2m2026-03-05T21:54:09.279283Z#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) })
1772747649.321 sable_services #x1B[2m2026-03-05T21:54:09.320805Z#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"))
1772747649.321 sable_services #x1B[2m2026-03-05T21:54:09.320850Z#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")
1772747649.325 sable_services #x1B[2m2026-03-05T21:54:09.325227Z#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(817515354946678784)), name: Nickname("jilles"), authorised_fingerprints: [] }
1772747649.326 sable_services #x1B[2m2026-03-05T21:54:09.325453Z#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(817515354967650307)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: Account(AccountId(Snowflake(817515354946678784))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515354946678784)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 817515354321326085, 99 => 817515337011834882) my clock=EventClock(1 => 817515354321326085, 99 => 817515337011834882)
1772747649.326 sable_services #x1B[2m2026-03-05T21:54:09.325751Z#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(817515354967650307)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: Account(AccountId(Snowflake(817515354946678784))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515354946678784)), 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:37459 (127.0.0.1:37459)
1772747649.367 sable_ircd     #x1B[2m2026-03-05T21:54:09.366705Z#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(817515354946678784))))) } }
1772747649.367 sable_ircd     #x1B[2m2026-03-05T21:54:09.367001Z#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
1772747649.367 sable_ircd     #x1B[2m2026-03-05T21:54:09.367079Z#x1B[0m #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
1772747649.367 sable_ircd     #x1B[2m2026-03-05T21:54:09.367144Z#x1B[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(817515354308743170))), UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515354946678784))) }))
1772747649.367 sable_ircd     #x1B[2m2026-03-05T21:54:09.367311Z#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(817515355143409670)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515354946678784))) }) }; event clock=EventClock(1 => 817515354321326085, 99 => 817515337011834882) my clock=EventClock(1 => 817515354321326085, 99 => 817515337011834882)
1772747649.368 sable_ircd     #x1B[2m2026-03-05T21:54:09.367687Z#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(817515355143409670)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515354946678784))) }) }) } #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:59237 (127.0.0.1:59237)
1772747649.370 sable_ircd     #x1B[2m2026-03-05T21:54:09.370249Z#x1B[0m #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(817515355143409670)), timestamp: 1772747649, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 3), old_account: None, new_account: Some(AccountId(Snowflake(817515354946678784))) }), users_to_notify: [] })
1772747649.371 sable_ircd     #x1B[2m2026-03-05T21:54:09.370298Z#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(817515355143409670)), timestamp: 1772747649, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 3), old_account: None, new_account: Some(AccountId(Snowflake(817515354946678784))) }), users_to_notify: [] }
1772747649.371 sable_ircd     #x1B[2m2026-03-05T21:54:09.370387Z#x1B[0m #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(817515355143409670)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] })
1772747649.371 sable_ircd     #x1B[2m2026-03-05T21:54:09.370418Z#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(817515355143409670)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] }
1772747649.371 sable_ircd     #x1B[2m2026-03-05T21:54:09.370539Z#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(817515354967650307)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: Account(AccountId(Snowflake(817515354946678784))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515354946678784)), 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(817515354967650307)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: Account(AccountId(Snowflake(817515354946678784))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515354946678784)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 817515354321326085, 99 => 817515337011834882) my clock=EventClock(1 => 817515355143409670, 99 => 817515337011834882)
1772747649.371 sable_ircd     #x1B[2m2026-03-05T21:54:09.371077Z#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(817515354967650307)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: Account(AccountId(Snowflake(817515354946678784))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515354946678784)), 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(817515354967650307)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: Account(AccountId(Snowflake(817515354946678784))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(817515354946678784)), 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:59237 (127.0.0.1:59237)
1772747649.374 sable_ircd     #x1B[2m2026-03-05T21:54:09.373499Z#x1B[0m #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(817515354967650307)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] })
1772747649.374 sable_ircd     #x1B[2m2026-03-05T21:54:09.373540Z#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(817515354967650307)), timestamp: 1772747649, change: EventComplete(EventComplete), users_to_notify: [] }
1772747649.412 sable_services #x1B[2m2026-03-05T21:54:09.411914Z#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(817515355143409670)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515354946678784))) }) }) } }#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(817515355143409670)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515354946678784))) }) }; event clock=EventClock(1 => 817515354321326085, 99 => 817515337011834882) my clock=EventClock(1 => 817515354321326085, 99 => 817515354967650307)
1772747649.412 sable_services #x1B[2m2026-03-05T21:54:09.412316Z#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(817515355143409670)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515354946678784))) }) }) } }#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(817515355143409670)), timestamp: 1772747649, clock: EventClock(1 => 817515354321326085, 99 => 817515337011834882), target: User(UserId(Snowflake(817515354308743170))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(817515354946678784))) }) }) } #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:37459 (127.0.0.1:37459)
1772747649.477 1 -> S: PING synchronize486.813582545
1772747649.477 S -> 1: REGISTER SUCCESS jilles :You have successfully registered
1772747649.478 sable_ircd     #x1B[2m2026-03-05T21:54:09.477504Z#x1B[0m #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
1772747649.478 sable_ircd     #x1B[2m2026-03-05T21:54:09.477590Z#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 synchronize486.813582545"
1772747650.232 sable_ircd     #x1B[2m2026-03-05T21:54:10.231471Z#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 :synchronize486.813582545
1772747650.232 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize486.813582545
1772747650.232 1 -> S: QUIT
1772747650.232 sable_ircd     #x1B[2m2026-03-05T21:54:10.232263Z#x1B[0m #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
1772747650.232 sable_ircd     #x1B[2m2026-03-05T21:54:10.232328Z#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"
1772747650.332 1 -> S: PING synchronize487.668749364
1772747650.333 sable_ircd     #x1B[2m2026-03-05T21:54:10.332598Z#x1B[0m #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
1772747650.333 sable_ircd     #x1B[2m2026-03-05T21:54:10.332682Z#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 synchronize487.668749364"
1772747651.086 sable_ircd     #x1B[2m2026-03-05T21:54:11.085510Z#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
1772747651.086 sable_ircd     #x1B[2m2026-03-05T21:54:11.085637Z#x1B[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))
1772747651.086 sable_ircd     #x1B[2m2026-03-05T21:54:11.085678Z#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)
1772747651.086 sable_ircd     #x1B[2m2026-03-05T21:54:11.085802Z#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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515355143409670, 99 => 817515354967650307) my clock=EventClock(1 => 817515355143409670, 99 => 817515354967650307)
1772747651.086 S -> 1: ERROR :Client quit
1772747651.086 sable_ircd     #x1B[2m2026-03-05T21:54:11.086076Z#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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), 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:59237 (127.0.0.1:59237)
1772747651.087 sable_ircd     #x1B[2m2026-03-05T21:54:11.086452Z#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
1772747651.087 sable_ircd     #x1B[2m2026-03-05T21:54:11.086551Z#x1B[0m #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
1772747651.088 sable_ircd     #x1B[2m2026-03-05T21:54:11.087956Z#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(817515362357612552)), timestamp: 1772747651, clock: EventClock(1 => 817515362349223943, 99 => 817515354967650307), target: User(UserId(Snowflake(817515354308743170))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515362349223943, 99 => 817515354967650307) my clock=EventClock(1 => 817515362349223943, 99 => 817515354967650307)
1772747651.088 sable_ircd     #x1B[2m2026-03-05T21:54:11.088279Z#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(817515362357612552)), timestamp: 1772747651, clock: EventClock(1 => 817515362349223943, 99 => 817515354967650307), target: User(UserId(Snowflake(817515354308743170))), 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:59237 (127.0.0.1:59237)
1772747651.088 sable_ircd     #x1B[2m2026-03-05T21:54:11.088388Z#x1B[0m #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(817515362349223943)), timestamp: 1772747651, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(817515354308743171)), user: UserId(Snowflake(817515354308743170)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747649 } }), users_to_notify: [] })
1772747651.088 sable_ircd     #x1B[2m2026-03-05T21:54:11.088436Z#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(817515362349223943)), timestamp: 1772747651, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(817515354308743171)), user: UserId(Snowflake(817515354308743170)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747649 } }), users_to_notify: [] }
1772747651.089 sable_ircd     #x1B[2m2026-03-05T21:54:11.088517Z#x1B[0m #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(817515362349223943)), timestamp: 1772747651, change: EventComplete(EventComplete), users_to_notify: [] })
1772747651.089 sable_ircd     #x1B[2m2026-03-05T21:54:11.088561Z#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(817515362349223943)), timestamp: 1772747651, change: EventComplete(EventComplete), users_to_notify: [] }
1772747651.092 sable_ircd     #x1B[2m2026-03-05T21:54:11.091932Z#x1B[0m #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(817515362357612552)), timestamp: 1772747651, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] })
1772747651.092 sable_ircd     #x1B[2m2026-03-05T21:54:11.091978Z#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(817515362357612552)), timestamp: 1772747651, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(817515354308743170)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] }
1772747651.092 sable_ircd     #x1B[2m2026-03-05T21:54:11.092056Z#x1B[0m #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(817515362357612552)), timestamp: 1772747651, change: EventComplete(EventComplete), users_to_notify: [] })
1772747651.092 sable_ircd     #x1B[2m2026-03-05T21:54:11.092087Z#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(817515362357612552)), timestamp: 1772747651, change: EventComplete(EventComplete), users_to_notify: [] }
1772747651.093 sable_services #x1B[2m2026-03-05T21:54:11.092694Z#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(817515362357612552)), timestamp: 1772747651, clock: EventClock(1 => 817515362349223943, 99 => 817515354967650307), target: User(UserId(Snowflake(817515354308743170))), 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(817515362357612552)), timestamp: 1772747651, clock: EventClock(1 => 817515362349223943, 99 => 817515354967650307), target: User(UserId(Snowflake(817515354308743170))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 817515362349223943, 99 => 817515354967650307) my clock=EventClock(1 => 817515355143409670, 99 => 817515354967650307)
1772747651.093 sable_services #x1B[2m2026-03-05T21:54:11.092755Z#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(817515362357612552)), timestamp: 1772747651, clock: EventClock(1 => 817515362349223943, 99 => 817515354967650307), target: User(UserId(Snowflake(817515354308743170))), 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(817515362349223943))]
1772747651.093 sable_services #x1B[2m2026-03-05T21:54:11.093138Z#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(817515362357612552)), timestamp: 1772747651, clock: EventClock(1 => 817515362349223943, 99 => 817515354967650307), target: User(UserId(Snowflake(817515354308743170))), 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(817515362357612552)), timestamp: 1772747651, clock: EventClock(1 => 817515362349223943, 99 => 817515354967650307), target: User(UserId(Snowflake(817515354308743170))), 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:37459 (127.0.0.1:37459)
1772747651.129 sable_services #x1B[2m2026-03-05T21:54:11.128638Z#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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), 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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 817515355143409670, 99 => 817515354967650307) my clock=EventClock(1 => 817515355143409670, 99 => 817515354967650307)
1772747651.129 sable_services #x1B[2m2026-03-05T21:54:11.128705Z#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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), 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(817515362357612552)), timestamp: 1772747651, clock: EventClock(1 => 817515362349223943, 99 => 817515354967650307), target: User(UserId(Snowflake(817515354308743170))), details: UserQuit(UserQuit { message: "Client Quit" }) }
1772747651.129 sable_services #x1B[2m2026-03-05T21:54:11.128975Z#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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), 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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), 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:37459 (127.0.0.1:37459)
1772747651.134 sable_ircd     #x1B[2m2026-03-05T21:54:11.133538Z#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(817515362349223943))]) } }#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(817515362349223943))]
1772747651.134 sable_ircd     #x1B[2m2026-03-05T21:54:11.133589Z#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(817515362349223943))]) } }#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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), details: UserDisconnect(UserDisconnect) }]
1772747651.175 sable_services #x1B[2m2026-03-05T21:54:11.174599Z#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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), 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(817515362349223943)), timestamp: 1772747651, clock: EventClock(1 => 817515355143409670, 99 => 817515354967650307), target: UserConnection(UserConnectionId(Snowflake(817515354308743171))), details: UserDisconnect(UserDisconnect) }]
1772747651.186 1 -> S: PING synchronize488.522810835
1772747651.187 1: connects to server.
1772747651.187 1 -> S: CAP REQ :sasl
1772747651.187 sable_ircd     #x1B[2m2026-03-05T21:54:11.186991Z#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 }
1772747651.187 sable_ircd     #x1B[2m2026-03-05T21:54:11.187105Z#x1B[0m #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
1772747651.187 sable_ircd     #x1B[2m2026-03-05T21:54:11.187149Z#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
1772747651.187 sable_ircd     #x1B[2m2026-03-05T21:54:11.187210Z#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
1772747651.187 sable_ircd     #x1B[2m2026-03-05T21:54:11.187388Z#x1B[0m #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
1772747651.187 sable_ircd     #x1B[2m2026-03-05T21:54:11.187435Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP REQ :sasl"
1772747651.188 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747651.188 sable_ircd     #x1B[2m2026-03-05T21:54:11.187555Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server CAP * ACK :sasl
1772747651.188 sable_ircd     #x1B[2m2026-03-05T21:54:11.187603Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mUpdateConnectionCaps(ConnectionId(ListenerId(0), 4), ClientCapabilitySet(8))
1772747651.188 S -> 1: :My.Little.Server CAP * ACK :sasl
1772747651.188 1 -> S: AUTHENTICATE PLAIN
1772747651.188 sable_ircd     #x1B[2m2026-03-05T21:54:11.188256Z#x1B[0m #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
1772747651.188 sable_ircd     #x1B[2m2026-03-05T21:54:11.188294Z#x1B[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"))
1772747651.188 sable_ircd     #x1B[2m2026-03-05T21:54:11.188357Z#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
1772747651.188 sable_ircd     #x1B[2m2026-03-05T21:54:11.188405Z#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: 488, tv_nsec: 523854960 }, 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
1772747651.189 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747651.189 sable_ircd     #x1B[2m2026-03-05T21:54:11.188650Z#x1B[0m #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
1772747651.189 sable_ircd     #x1B[2m2026-03-05T21:54:11.188712Z#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"
1772747651.189 sable_ircd     #x1B[2m2026-03-05T21:54:11.188862Z#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(817515362781237252))
1772747651.189 sable_ircd     #x1B[2m2026-03-05T21:54:11.188983Z#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:59237", fingerprint: "09e66d97e33282529120b5647bb789d72174ca49" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(BeginAuthenticate(SaslSessionId(Snowflake(817515362781237252)), "PLAIN")) }
1772747651.189 sable_ircd     #x1B[2m2026-03-05T21:54:11.189215Z#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(817515362781237252)), "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:59237 (127.0.0.1:59237)
1772747651.191 sable_ircd     #x1B[2m2026-03-05T21:54:11.191362Z#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) })
1772747651.233 sable_services #x1B[2m2026-03-05T21:54:11.232681Z#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(817515362781237252)), "PLAIN"))
1772747651.233 sable_services #x1B[2m2026-03-05T21:54:11.232721Z#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(817515362781237252)) #x1B[3mmechanism#x1B[0m#x1B[2m=#x1B[0m"PLAIN"
1772747651.274 sable_ircd     #x1B[2m2026-03-05T21:54:11.273625Z#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([])))) } }
1772747651.274 sable_ircd     #x1B[2m2026-03-05T21:54:11.273801Z#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 :+
1772747651.274 sable_ircd     #x1B[2m2026-03-05T21:54:11.273874Z#x1B[0m #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
1772747651.274 S -> 1: AUTHENTICATE :+
1772747651.275 1 -> S: AUTHENTICATE AGppbGxlcwA=
1772747651.275 sable_ircd     #x1B[2m2026-03-05T21:54:11.274730Z#x1B[0m #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
1772747651.275 sable_ircd     #x1B[2m2026-03-05T21:54:11.274781Z#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 AGppbGxlcwA="
1772747651.275 sable_ircd     #x1B[2m2026-03-05T21:54:11.274965Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::command::handlers::services::sasl#x1B[0m#x1B[2m:#x1B[0m Resuming SASL session #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515362781237252))
1772747651.276 sable_ircd     #x1B[2m2026-03-05T21:54:11.275110Z#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:59237", fingerprint: "09e66d97e33282529120b5647bb789d72174ca49" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(Authenticate(SaslSessionId(Snowflake(817515362781237252)), [0, 106, 105, 108, 108, 101, 115, 0])) }
1772747651.276 sable_ircd     #x1B[2m2026-03-05T21:54:11.275422Z#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(Authenticate(SaslSessionId(Snowflake(817515362781237252)), [0, 106, 105, 108, 108, 101, 115, 0])) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:59237 (127.0.0.1:59237)
1772747651.278 sable_ircd     #x1B[2m2026-03-05T21:54:11.277961Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(36) })
1772747651.319 sable_services #x1B[2m2026-03-05T21:54:11.318702Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(Authenticate(SaslSessionId(Snowflake(817515362781237252)), [0, 106, 105, 108, 108, 101, 115, 0]))
1772747651.319 sable_services #x1B[2m2026-03-05T21:54:11.318746Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got authenticate data #x1B[3msession#x1B[0m#x1B[2m=#x1B[0mSaslSessionId(Snowflake(817515362781237252)) #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0m[0, 106, 105, 108, 108, 101, 115, 0]
1772747651.323 sable_services #x1B[2m2026-03-05T21:54:11.322713Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server::sasl::plain#x1B[0m#x1B[2m:#x1B[0m wrong password
1772747651.364 sable_ircd     #x1B[2m2026-03-05T21:54:11.363581Z#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(Fail))) } }
1772747651.364 sable_ircd     #x1B[2m2026-03-05T21:54:11.363800Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server 904 * :SASL authentication failed
1772747651.364 sable_ircd     #x1B[2m2026-03-05T21:54:11.363856Z#x1B[0m #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
1772747651.364 S -> 1: :My.Little.Server 904 * :SASL authentication failed
1772747651.367 sable_ircd     #x1B[2m2026-03-05T21:54:11.366519Z#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
1772747651.367 sable_ircd     #x1B[2m2026-03-05T21:54:11.366744Z#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
1772747651.367 sable_ircd     #x1B[2m2026-03-05T21:54:11.366899Z#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
1772747651.367 sable_ircd     #x1B[2m2026-03-05T21:54:11.366906Z#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:37459, peer: 127.0.0.1:59609, fd: 19 }) } #x1B[3mmessage_sender#x1B[0m#x1B[2m=#x1B[0mUnboundedSender { chan: Tx { inner: Chan { tx: Tx { block_tail: 0x5644ac685010, 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
1772747651.369 1: disconnects from server.