--------------------------------- Captured Out --------------------------------- ---- new test ---- 1781949961.243 sable_ircd uid=1001 1781949961.247 sable_ircd #x1B[2m2026-06-20T10:06:01.246773Z#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(856112648666746880)), timestamp: 1781949961, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1781949961, name: ServerName("My.Little.Server"), ts: 1781949961, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock() 1781949961.247 sable_ircd #x1B[2m2026-06-20T10:06:01.246900Z#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 1781949961.247 sable_ircd #x1B[2m2026-06-20T10:06:01.247100Z#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(856112648666746880)), timestamp: 1781949961, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1781949961, name: ServerName("My.Little.Server"), ts: 1781949961, 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 1781949961.248 sable_ircd #x1B[2m2026-06-20T10:06:01.247279Z#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(856112648670941185)), timestamp: 1781949961, clock: EventClock(1 => 856112648666746880), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1781949961 }) }; event clock=EventClock(1 => 856112648666746880) my clock=EventClock(1 => 856112648666746880) 1781949961.248 sable_ircd #x1B[2m2026-06-20T10:06:01.247338Z#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 1781949961.248 sable_ircd #x1B[2m2026-06-20T10:06:01.247499Z#x1B[0m #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(856112648666746880)), timestamp: 1781949961, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }) 1781949961.248 sable_ircd #x1B[2m2026-06-20T10:06:01.247542Z#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(856112648666746880)), timestamp: 1781949961, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] } 1781949961.248 sable_ircd #x1B[2m2026-06-20T10:06:01.247579Z#x1B[0m #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(856112648666746880)), timestamp: 1781949961, change: EventComplete(EventComplete), users_to_notify: [] }) 1781949961.248 sable_ircd #x1B[2m2026-06-20T10:06:01.247599Z#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(856112648666746880)), timestamp: 1781949961, change: EventComplete(EventComplete), users_to_notify: [] } 1781949961.248 sable_ircd #x1B[2m2026-06-20T10:06:01.247639Z#x1B[0m #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(856112648670941185)), timestamp: 1781949961, change: EventComplete(EventComplete), users_to_notify: [] }) 1781949961.248 sable_ircd #x1B[2m2026-06-20T10:06:01.247653Z#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(856112648670941185)), timestamp: 1781949961, change: EventComplete(EventComplete), users_to_notify: [] } 1781949961.249 sable_ircd #x1B[2m2026-06-20T10:06:01.248644Z#x1B[0m #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 1781949961.339 sable_ircd #x1B[2m2026-06-20T10:06:01.339251Z#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 } 1781949961.339 sable_ircd #x1B[2m2026-06-20T10:06:01.339420Z#x1B[0m #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 1781949961.339 sable_ircd #x1B[2m2026-06-20T10:06:01.339467Z#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 1781949961.340 sable_ircd #x1B[2m2026-06-20T10:06:01.339545Z#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 1781949961.340 sable_ircd #x1B[2m2026-06-20T10:06:01.339750Z#x1B[0m #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 1781949961.340 sable_ircd #x1B[2m2026-06-20T10:06:01.339808Z#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" 1781949961.340 sable_ircd #x1B[2m2026-06-20T10:06:01.339935Z#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 1781949961.340 sable_ircd #x1B[2m2026-06-20T10:06:01.340019Z#x1B[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)) 1781949961.340 sable_ircd #x1B[2m2026-06-20T10:06:01.340069Z#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) 1781949961.341 sable_ircd #x1B[2m2026-06-20T10:06:01.340478Z#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 1781949961.341 sable_ircd #x1B[2m2026-06-20T10:06:01.340553Z#x1B[0m #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 1781949961.341 sable_ircd #x1B[2m2026-06-20T10:06:01.340770Z#x1B[0m #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 1781949961.360 1: connects to server. 1781949961.360 1 -> S: CAP LS 302 1781949961.361 sable_ircd #x1B[2m2026-06-20T10:06:01.360493Z#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 } 1781949961.361 sable_ircd #x1B[2m2026-06-20T10:06:01.360570Z#x1B[0m #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 1781949961.361 sable_ircd #x1B[2m2026-06-20T10:06:01.360597Z#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 1781949961.361 sable_ircd #x1B[2m2026-06-20T10:06:01.360648Z#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 1781949961.361 sable_ircd #x1B[2m2026-06-20T10:06:01.360766Z#x1B[0m #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 1781949961.361 sable_ircd #x1B[2m2026-06-20T10:06:01.360808Z#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" 1781949961.361 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname1781949961.361 sable_ircd #x1B[2m2026-06-20T10:06:01.360906Z#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 1781949961.361 S -> 1: :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 1781949961.361 1 -> S: CAP REQ :batch labeled-response 1781949961.361 sable_ircd #x1B[2m2026-06-20T10:06:01.361437Z#x1B[0m #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 1781949961.362 sable_ircd #x1B[2m2026-06-20T10:06:01.361487Z#x1B[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")) 1781949961.362 sable_ircd #x1B[2m2026-06-20T10:06:01.361525Z#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 1781949961.362 sable_ircd #x1B[2m2026-06-20T10:06:01.361557Z#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: 340, tv_nsec: 281582858 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(), realname: OnceLock(), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1781949961.362 sable_ircd #x1B[2m2026-06-20T10:06:01.361741Z#x1B[0m #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 1781949961.362 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1781949961.362 sable_ircd #x1B[2m2026-06-20T10:06:01.361790Z#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 REQ :batch labeled-response" 1781949961.362 sable_ircd #x1B[2m2026-06-20T10:06:01.361907Z#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 * ACK :batch labeled-response 1781949961.362 sable_ircd #x1B[2m2026-06-20T10:06:01.361936Z#x1B[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), 2), ClientCapabilitySet(48)) 1781949961.402 S -> 1: :My.Little.Server CAP * ACK :batch labeled-response 1781949961.402 1 -> S: NICK foo 1781949961.402 1 -> S: USER username * * :Realname 1781949961.402 1 -> S: CAP END 1781949961.403 sable_ircd #x1B[2m2026-06-20T10:06:01.402959Z#x1B[0m #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 1781949961.403 sable_ircd #x1B[2m2026-06-20T10:06:01.403031Z#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 foo" 1781949961.403 sable_ircd #x1B[2m2026-06-20T10:06:01.403248Z#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: 340, tv_nsec: 281582858 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("foo")), realname: OnceLock(), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1781949961.403 sable_ircd #x1B[2m2026-06-20T10:06:01.403321Z#x1B[0m #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 1781949961.403 sable_ircd #x1B[2m2026-06-20T10:06:01.403350Z#x1B[0m #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 1781949961.403 sable_ircd #x1B[2m2026-06-20T10:06:01.403372Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER username * * :Realname" 1781949961.403 sable_ircd #x1B[2m2026-06-20T10:06:01.403428Z#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: 340, tv_nsec: 281582858 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("foo")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1781949961.403 sable_ircd #x1B[2m2026-06-20T10:06:01.403465Z#x1B[0m #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 1781949961.404 sable_ircd #x1B[2m2026-06-20T10:06:01.403485Z#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 END" 1781949961.517 sable_history #x1B[2m2027-06-20T10:28:29.112647Z#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(988389872966508571)), timestamp: 1813487309, clock: EventClock(1 => 988383817729314817, 50 => 988389621308268570), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487309 }) }; event clock=EventClock(1 => 988383817729314817, 50 => 988389621308268570) my clock=EventClock(1 => 988383817729314817, 50 => 988389621308268570) 1781949961.517 sable_history #x1B[2m2027-06-20T10:28:29.114515Z#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 1781949961.890 sable_history #x1B[2m2027-06-20T10:34:05.530168Z#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(988391284005871650)), timestamp: 1813487645, clock: EventClock(1 => 988384221888253974, 50 => 988391032347631649, 99 => 988384225374121988), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487645 }) }; event clock=EventClock(1 => 988384221888253974, 50 => 988391032347631649, 99 => 988384225374121988) my clock=EventClock(1 => 988384221888253974, 50 => 988391032347631649, 99 => 988384225374121988) 1781949961.891 sable_history #x1B[2m2027-06-20T10:34:05.531861Z#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 1781949961.930 sable_history #x1B[2m2027-06-20T10:28:29.066967Z#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(988389872777764891)), timestamp: 1813487309, clock: EventClock(1 => 988383819516088321, 50 => 988389621119524890), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487309 }) }; event clock=EventClock(1 => 988383819516088321, 50 => 988389621119524890) my clock=EventClock(1 => 988383819516088321, 50 => 988389621119524890) 1781949961.930 sable_history #x1B[2m2027-06-20T10:28:29.068761Z#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 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.156496Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::complete_progress #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 340, tv_nsec: 281582858 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("foo")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mflag#x1B[0m#x1B[2m=#x1B[0mCapNegotiation #x1B[3mresult#x1B[0m#x1B[2m=#x1B[0mtrue 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.156630Z#x1B[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)) 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.156893Z#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(856112652483563522)), timestamp: 1781949962, clock: EventClock(1 => 856112648670941185), target: User(UserId(Snowflake(856112652483563520))), details: NewUser(NewUser { nickname: Nickname("foo"), username: Username("username"), visible_hostname: Hostname("localhost"), realname: Realname("Realname"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(856112652483563521)), NewUserConnection { user: UserId(Snowflake(856112652483563520)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1781949962 })) }) }; event clock=EventClock(1 => 856112648670941185) my clock=EventClock(1 => 856112648670941185) 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.156986Z#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 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.157275Z#x1B[0m #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(856112652483563522)), timestamp: 1781949962, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(856112652483563520)), 1) }), users_to_notify: [] }) 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.157315Z#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(856112652483563522)), timestamp: 1781949962, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(856112652483563520)), 1) }), users_to_notify: [] } 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.157362Z#x1B[0m #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(856112652483563522)), timestamp: 1781949962, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(856112652483563520)), 2), connection: UserConnectionId(Snowflake(856112652483563521)) }), users_to_notify: [] }) 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.157385Z#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(856112652483563522)), timestamp: 1781949962, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(856112652483563520)), 2), connection: UserConnectionId(Snowflake(856112652483563521)) }), users_to_notify: [] } 1781949962.157 sable_ircd #x1B[2m2026-06-20T10:06:02.157445Z#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 foo :Welcome to the test Internet Relay Chat network, foo 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157478Z#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 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157511Z#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 foo :This server was created 2026-06-20 10:06:02.157498157 UTC 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157535Z#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 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157579Z#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 foo EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157602Z#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 foo :MOTD File is missing 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157638Z#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): :foo!username@localhost MODE foo + 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157658Z#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 foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157705Z#x1B[0m #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(856112652483563522)), timestamp: 1781949962, change: EventComplete(EventComplete), users_to_notify: [] }) 1781949962.158 sable_ircd #x1B[2m2026-06-20T10:06:02.157727Z#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(856112652483563522)), timestamp: 1781949962, change: EventComplete(EventComplete), users_to_notify: [] } 1781949962.158 S -> 1: :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo 1781949962.158 1 -> S: PING foo 1781949962.159 sable_ircd #x1B[2m2026-06-20T10:06:02.158519Z#x1B[0m #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 1781949962.159 sable_ircd #x1B[2m2026-06-20T10:06:02.158568Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING foo" 1781949962.258 1 -> S: PING synchronize341.179332066 1781949962.259 S -> 1: :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1781949962.259 S -> 1: :My.Little.Server 003 foo :This server was created 2026-06-20 10:06:02.157498157 UTC 1781949962.259 S -> 1: :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1781949962.259 S -> 1: :My.Little.Server 005 foo EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1781949962.259 S -> 1: :My.Little.Server 422 foo :MOTD File is missing 1781949962.259 S -> 1: :foo!username@localhost MODE foo + 1781949962.259 S -> 1: :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1781949962.259 sable_ircd #x1B[2m2026-06-20T10:06:02.258984Z#x1B[0m #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 1781949962.259 sable_ircd #x1B[2m2026-06-20T10:06:02.259086Z#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 synchronize341.179332066" 1781949962.461 sable_history #x1B[2m2027-06-20T10:28:30.698524Z#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(988389879618674715)), timestamp: 1813487310, clock: EventClock(1 => 988383821600657409, 50 => 988389627960434714), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487310 }) }; event clock=EventClock(1 => 988383821600657409, 50 => 988389627960434714) my clock=EventClock(1 => 988383821600657409, 50 => 988389627960434714) 1781949962.461 sable_history #x1B[2m2027-06-20T10:28:30.701058Z#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 1781949962.502 sable_history #x1B[2m2027-06-20T10:36:53.676972Z#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(988391989265506341)), timestamp: 1813487813, clock: EventClock(1 => 988384174354206742, 50 => 988391737603072036, 99 => 988384179501019140), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487813 }) }; event clock=EventClock(1 => 988384174354206742, 50 => 988391737603072036, 99 => 988384179501019140) my clock=EventClock(1 => 988384174354206742, 50 => 988391737603072036, 99 => 988384179501019140) 1781949962.502 sable_history #x1B[2m2027-06-20T10:36:53.679405Z#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 1781949962.915 sable_history #x1B[2m2027-06-20T10:28:29.916580Z#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(988389876338728987)), timestamp: 1813487309, clock: EventClock(1 => 988383823714586625, 50 => 988389624680488986), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487309 }) }; event clock=EventClock(1 => 988383823714586625, 50 => 988389624680488986) my clock=EventClock(1 => 988383823714586625, 50 => 988389624680488986) 1781949962.915 sable_history #x1B[2m2027-06-20T10:28:29.918747Z#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 1781949963.012 sable_ircd #x1B[2m2026-06-20T10:06:03.012296Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server PONG My.Little.Server :foo 1781949963.013 S -> 1: :My.Little.Server PONG My.Little.Server :foo 1781949963.371 sable_history #x1B[2m2027-06-20T10:28:30.508301Z#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(988389878821756956)), timestamp: 1813487310, clock: EventClock(1 => 988383903788044303, 50 => 988389627163516955, 99 => 988383893860528132), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487310 }) }; event clock=EventClock(1 => 988383903788044303, 50 => 988389627163516955, 99 => 988383893860528132) my clock=EventClock(1 => 988383903788044303, 50 => 988389627163516955, 99 => 988383893860528132) 1781949963.371 sable_history #x1B[2m2027-06-20T10:28:30.510011Z#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 1781949963.464 sable_history #x1B[2m2027-06-20T10:39:42.581401Z#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(988392697700229160)), timestamp: 1813487982, clock: EventClock(1 => 988384128099422230, 50 => 988392446041989159, 99 => 988384132969410564), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487982 }) }; event clock=EventClock(1 => 988384128099422230, 50 => 988392446041989159, 99 => 988384132969410564) my clock=EventClock(1 => 988384128099422230, 50 => 988392446041989159, 99 => 988384132969410564) 1781949963.464 sable_history #x1B[2m2027-06-20T10:39:42.583179Z#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 1781949964.014 1: waiting… 1781949964.017 sable_ircd #x1B[2m2026-06-20T10:06:04.017006Z#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 :synchronize341.179332066 1781949964.018 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize341.179332066 1781949964.442 sable_history #x1B[2m2027-06-20T10:42:41.909208Z#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(988393449856376875)), timestamp: 1813488161, clock: EventClock(1 => 988384078828933142, 50 => 988393198189748266, 99 => 988384090091040772), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813488161 }) }; event clock=EventClock(1 => 988384078828933142, 50 => 988393198189748266, 99 => 988384090091040772) my clock=EventClock(1 => 988384078828933142, 50 => 988393198189748266, 99 => 988384090091040772) 1781949964.442 sable_history #x1B[2m2027-06-20T10:42:41.910951Z#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 1781949964.719 1 -> S: @label=label1 JOIN #valid,inv@lid 1781949964.719 sable_ircd #x1B[2m2026-06-20T10:06:04.719129Z#x1B[0m #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 1781949964.719 sable_ircd #x1B[2m2026-06-20T10:06:04.719207Z#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"@label=label1 JOIN #valid,inv@lid" 1781949964.819 1 -> S: PING synchronize343.739723131 1781949964.819 sable_ircd #x1B[2m2026-06-20T10:06:04.819395Z#x1B[0m #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 1781949964.819 sable_ircd #x1B[2m2026-06-20T10:06:04.819472Z#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 synchronize343.739723131" 1781949964.824 sable_history #x1B[2m2027-06-20T10:32:15.984946Z#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(988390824540839968)), timestamp: 1813487535, clock: EventClock(1 => 988384519864193057, 50 => 988390572878405663, 99 => 988384522846744581), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487535 }) }; event clock=EventClock(1 => 988384519864193057, 50 => 988390572878405663, 99 => 988384522846744581) my clock=EventClock(1 => 988384519864193057, 50 => 988390572878405663, 99 => 988384522846744581) 1781949964.824 sable_history #x1B[2m2027-06-20T10:32:15.986613Z#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 1781949965.056 sable_history #x1B[2m2027-06-20T10:29:28.436538Z#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(988390121789399068)), timestamp: 1813487368, clock: EventClock(1 => 988383816034816001, 50 => 988389870131159067), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487368 }) }; event clock=EventClock(1 => 988383816034816001, 50 => 988389870131159067) my clock=EventClock(1 => 988383816034816001, 50 => 988389870131159067) 1781949965.056 sable_history #x1B[2m2027-06-20T10:29:28.438497Z#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 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.070676Z#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(856112664705765379)), timestamp: 1781949965, clock: EventClock(1 => 856112652483563522), target: Channel(ChannelId(Snowflake(856112664705765378))), details: NewChannel(NewChannel { name: ChannelName("#valid"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 856112652483563522) my clock=EventClock(1 => 856112652483563522) 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.070869Z#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 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.070963Z#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(856112664705765380)), timestamp: 1781949965, clock: EventClock(1 => 856112664705765379), target: Membership(MembershipId(UserId(Snowflake(856112652483563520)), ChannelId(Snowflake(856112664705765378)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(856112664705765378)), user: UserId(Snowflake(856112652483563520)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 856112664705765379) my clock=EventClock(1 => 856112664705765379) 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.071103Z#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 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.071120Z#x1B[0m #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 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.071368Z#x1B[0m #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(856112664705765379)), timestamp: 1781949965, change: EventComplete(EventComplete), users_to_notify: [] }) 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.071404Z#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(856112664705765379)), timestamp: 1781949965, change: EventComplete(EventComplete), users_to_notify: [] } 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.071447Z#x1B[0m #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(856112664705765380)), timestamp: 1781949965, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(856112652483563520)), ChannelId(Snowflake(856112664705765378))), user: HistoricUserId(UserId(Snowflake(856112652483563520)), 2) }), users_to_notify: [UserId(Snowflake(856112652483563520))] }) 1781949965.071 sable_ircd #x1B[2m2026-06-20T10:06:05.071467Z#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(856112664705765380)), timestamp: 1781949965, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(856112652483563520)), ChannelId(Snowflake(856112664705765378))), user: HistoricUserId(UserId(Snowflake(856112652483563520)), 2) }), users_to_notify: [UserId(Snowflake(856112652483563520))] } 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071522Z#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): @label=label1 BATCH +68ab39964c5a45b8b4434c7c71333bfa labeled-response 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071544Z#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): @batch=68ab39964c5a45b8b4434c7c71333bfa :My.Little.Server 479 foo inv@lid :Illegal channel name 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071556Z#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): @batch=68ab39964c5a45b8b4434c7c71333bfa :foo!username@localhost JOIN #valid 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071578Z#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): @batch=68ab39964c5a45b8b4434c7c71333bfa :foo!username@localhost MODE #valid +o foo 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071614Z#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): @batch=68ab39964c5a45b8b4434c7c71333bfa :My.Little.Server 353 foo = #valid :@foo 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071630Z#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): @batch=68ab39964c5a45b8b4434c7c71333bfa :My.Little.Server 366 foo #valid :End of names list 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071663Z#x1B[0m #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(856112664705765380)), timestamp: 1781949965, change: EventComplete(EventComplete), users_to_notify: [] }) 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071678Z#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(856112664705765380)), timestamp: 1781949965, change: EventComplete(EventComplete), users_to_notify: [] } 1781949965.072 sable_ircd #x1B[2m2026-06-20T10:06:05.071695Z#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): BATCH -68ab39964c5a45b8b4434c7c71333bfa 1781949965.072 S -> 1: @label=label1 BATCH +68ab39964c5a45b8b4434c7c71333bfa labeled-response 1781949965.072 S -> 1: @batch=68ab39964c5a45b8b4434c7c71333bfa :My.Little.Server 479 foo inv@lid :Illegal channel name 1781949965.072 S -> 1: @batch=68ab39964c5a45b8b4434c7c71333bfa :foo!username@localhost JOIN #valid 1781949965.072 S -> 1: @batch=68ab39964c5a45b8b4434c7c71333bfa :foo!username@localhost MODE #valid +o foo 1781949965.072 S -> 1: @batch=68ab39964c5a45b8b4434c7c71333bfa :My.Little.Server 353 foo = #valid :@foo 1781949965.072 S -> 1: @batch=68ab39964c5a45b8b4434c7c71333bfa :My.Little.Server 366 foo #valid :End of names list 1781949965.072 S -> 1: BATCH -68ab39964c5a45b8b4434c7c71333bfa 1781949965.518 sable_history #x1B[2m2027-06-20T10:29:29.113735Z#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(988390124628942876)), timestamp: 1813487369, clock: EventClock(1 => 988383817729314817, 50 => 988389872966508571), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487369 }) }; event clock=EventClock(1 => 988383817729314817, 50 => 988389872966508571) my clock=EventClock(1 => 988383817729314817, 50 => 988389872966508571) 1781949965.518 sable_history #x1B[2m2027-06-20T10:29:29.115560Z#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 1781949965.890 sable_history #x1B[2m2027-06-20T10:35:05.530194Z#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(988391535664111651)), timestamp: 1813487705, clock: EventClock(1 => 988384221888253974, 50 => 988391284005871650, 99 => 988384225374121988), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487705 }) }; event clock=EventClock(1 => 988384221888253974, 50 => 988391284005871650, 99 => 988384225374121988) my clock=EventClock(1 => 988384221888253974, 50 => 988391284005871650, 99 => 988384225374121988) 1781949965.890 sable_history #x1B[2m2027-06-20T10:35:05.532037Z#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 1781949965.930 sable_history #x1B[2m2027-06-20T10:29:29.067483Z#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(988390124436004892)), timestamp: 1813487369, clock: EventClock(1 => 988383819516088321, 50 => 988389872777764891), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1813487369 }) }; event clock=EventClock(1 => 988383819516088321, 50 => 988389872777764891) my clock=EventClock(1 => 988383819516088321, 50 => 988389872777764891) 1781949965.930 sable_history #x1B[2m2027-06-20T10:29:29.069323Z#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 1781949966.073 1: waiting… 1781949966.077 sable_ircd #x1B[2m2026-06-20T10:06:06.076735Z#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 :synchronize343.739723131 1781949966.077 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize343.739723131 1781949966.080 1: disconnects from server.