--------------------------------- Captured Out --------------------------------- ---- new test ---- 1774772998.161 sable_ircd uid=1001 1774772998.164 sable_ircd #x1B[2m2026-03-29T08:29:58.164309Z#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(826010283704061952)), timestamp: 1774772998, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1774772998, name: ServerName("My.Little.Server"), ts: 1774772998, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock() 1774772998.164 sable_ircd #x1B[2m2026-03-29T08:29:58.164414Z#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 1774772998.165 sable_ircd #x1B[2m2026-03-29T08:29:58.164615Z#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(826010283704061952)), timestamp: 1774772998, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1774772998, name: ServerName("My.Little.Server"), ts: 1774772998, 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 1774772998.165 sable_ircd #x1B[2m2026-03-29T08:29:58.164822Z#x1B[0m #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(826010283704061952)), timestamp: 1774772998, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }) 1774772998.165 sable_ircd #x1B[2m2026-03-29T08:29:58.164861Z#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(826010283704061952)), timestamp: 1774772998, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] } 1774772998.165 sable_ircd #x1B[2m2026-03-29T08:29:58.164892Z#x1B[0m #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(826010283704061952)), timestamp: 1774772998, change: EventComplete(EventComplete), users_to_notify: [] }) 1774772998.165 sable_ircd #x1B[2m2026-03-29T08:29:58.164917Z#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(826010283704061952)), timestamp: 1774772998, change: EventComplete(EventComplete), users_to_notify: [] } 1774772998.165 sable_ircd #x1B[2m2026-03-29T08:29:58.165293Z#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(826010283708256257)), timestamp: 1774772998, clock: EventClock(1 => 826010283704061952), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1774772998 }) }; event clock=EventClock(1 => 826010283704061952) my clock=EventClock(1 => 826010283704061952) 1774772998.165 sable_ircd #x1B[2m2026-03-29T08:29:58.165368Z#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 1774772998.166 sable_ircd #x1B[2m2026-03-29T08:29:58.165587Z#x1B[0m #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(826010283708256257)), timestamp: 1774772998, change: EventComplete(EventComplete), users_to_notify: [] }) 1774772998.166 sable_ircd #x1B[2m2026-03-29T08:29:58.165654Z#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(826010283708256257)), timestamp: 1774772998, change: EventComplete(EventComplete), users_to_notify: [] } 1774772998.166 sable_ircd #x1B[2m2026-03-29T08:29:58.165814Z#x1B[0m #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 1774772998.257 sable_ircd #x1B[2m2026-03-29T08:29:58.257226Z#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 } 1774772998.257 sable_ircd #x1B[2m2026-03-29T08:29:58.257338Z#x1B[0m #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 1774772998.257 sable_ircd #x1B[2m2026-03-29T08:29:58.257416Z#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 1774772998.258 sable_ircd #x1B[2m2026-03-29T08:29:58.257486Z#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 1774772998.258 sable_ircd #x1B[2m2026-03-29T08:29:58.257655Z#x1B[0m #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 1774772998.258 sable_ircd #x1B[2m2026-03-29T08:29:58.257720Z#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" 1774772998.258 sable_ircd #x1B[2m2026-03-29T08:29:58.257822Z#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 1774772998.258 sable_ircd #x1B[2m2026-03-29T08:29:58.257918Z#x1B[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)) 1774772998.258 sable_ircd #x1B[2m2026-03-29T08:29:58.257950Z#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) 1774772998.258 sable_ircd #x1B[2m2026-03-29T08:29:58.258377Z#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 1774772998.258 sable_ircd #x1B[2m2026-03-29T08:29:58.258459Z#x1B[0m #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 1774772998.259 sable_ircd #x1B[2m2026-03-29T08:29:58.258682Z#x1B[0m #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 1774772998.278 bar: connects to server. 1774772998.278 bar -> S: CAP LS 302 1774772998.279 sable_ircd #x1B[2m2026-03-29T08:29:58.278438Z#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 } 1774772998.279 sable_ircd #x1B[2m2026-03-29T08:29:58.278563Z#x1B[0m #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 1774772998.279 sable_ircd #x1B[2m2026-03-29T08:29:58.278610Z#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 1774772998.279 sable_ircd #x1B[2m2026-03-29T08:29:58.278682Z#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 1774772998.279 sable_ircd #x1B[2m2026-03-29T08:29:58.278819Z#x1B[0m #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 1774772998.279 sable_ircd #x1B[2m2026-03-29T08:29:58.278884Z#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" 1774772998.279 S -> bar: :My.Little.Server NOTICE * :*** Looking up your hostname 1774772998.279 sable_ircd #x1B[2m2026-03-29T08:29:58.279026Z#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 1774772998.279 S -> bar: :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 1774772998.279 bar -> S: CAP REQ :draft/channel-rename 1774772998.280 sable_ircd #x1B[2m2026-03-29T08:29:58.279603Z#x1B[0m #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 1774772998.280 sable_ircd #x1B[2m2026-03-29T08:29:58.279684Z#x1B[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")) 1774772998.280 sable_ircd #x1B[2m2026-03-29T08:29:58.279739Z#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 1774772998.280 sable_ircd #x1B[2m2026-03-29T08:29:58.279779Z#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: 302, tv_nsec: 497040440 }, 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 1774772998.280 sable_ircd #x1B[2m2026-03-29T08:29:58.279987Z#x1B[0m #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 1774772998.280 S -> bar: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1774772998.280 sable_ircd #x1B[2m2026-03-29T08:29:58.280066Z#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 :draft/channel-rename" 1774772998.280 sable_ircd #x1B[2m2026-03-29T08:29:58.280253Z#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 :draft/channel-rename 1774772998.280 sable_ircd #x1B[2m2026-03-29T08:29:58.280290Z#x1B[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(524288)) 1774772998.321 S -> bar: :My.Little.Server CAP * ACK :draft/channel-rename 1774772998.321 bar -> S: NICK bar 1774772998.321 bar -> S: USER username * * :Realname 1774772998.321 bar -> S: CAP END 1774772998.321 sable_ircd #x1B[2m2026-03-29T08:29:58.321134Z#x1B[0m #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 1774772998.321 sable_ircd #x1B[2m2026-03-29T08:29:58.321182Z#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 bar" 1774772998.321 sable_ircd #x1B[2m2026-03-29T08:29:58.321312Z#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: 302, tv_nsec: 497040440 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("bar")), 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 1774772998.321 sable_ircd #x1B[2m2026-03-29T08:29:58.321384Z#x1B[0m #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 1774772998.321 sable_ircd #x1B[2m2026-03-29T08:29:58.321414Z#x1B[0m #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 1774772998.321 sable_ircd #x1B[2m2026-03-29T08:29:58.321442Z#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" 1774772998.322 sable_ircd #x1B[2m2026-03-29T08:29:58.321505Z#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: 302, tv_nsec: 497040440 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("bar")), 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 1774772998.322 sable_ircd #x1B[2m2026-03-29T08:29:58.321554Z#x1B[0m #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 1774772998.322 sable_ircd #x1B[2m2026-03-29T08:29:58.321581Z#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" 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.075472Z#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: 302, tv_nsec: 497040440 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("bar")), 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 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.075594Z#x1B[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)) 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.075857Z#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(826010287525072898)), timestamp: 1774772999, clock: EventClock(1 => 826010283708256257), target: User(UserId(Snowflake(826010287525072896))), details: NewUser(NewUser { nickname: Nickname("bar"), 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(826010287525072897)), NewUserConnection { user: UserId(Snowflake(826010287525072896)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1774772999 })) }) }; event clock=EventClock(1 => 826010283708256257) my clock=EventClock(1 => 826010283708256257) 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.075941Z#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 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.076239Z#x1B[0m #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(826010287525072898)), timestamp: 1774772999, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826010287525072896)), 1) }), users_to_notify: [] }) 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.076274Z#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(826010287525072898)), timestamp: 1774772999, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826010287525072896)), 1) }), users_to_notify: [] } 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.076317Z#x1B[0m #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(826010287525072898)), timestamp: 1774772999, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826010287525072896)), 2), connection: UserConnectionId(Snowflake(826010287525072897)) }), users_to_notify: [] }) 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.076337Z#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(826010287525072898)), timestamp: 1774772999, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826010287525072896)), 2), connection: UserConnectionId(Snowflake(826010287525072897)) }), users_to_notify: [] } 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.076385Z#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 bar :Welcome to the test Internet Relay Chat network, bar 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.076430Z#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 bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1774772999.076 sable_ircd #x1B[2m2026-03-29T08:29:59.076473Z#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 bar :This server was created 2026-03-29 08:29:59.076458811 UTC 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.076496Z#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 bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.076548Z#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 bar 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 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.076597Z#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 bar :MOTD File is missing 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.076666Z#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): :bar!username@localhost MODE bar + 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.076700Z#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 bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.076760Z#x1B[0m #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(826010287525072898)), timestamp: 1774772999, change: EventComplete(EventComplete), users_to_notify: [] }) 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.076793Z#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(826010287525072898)), timestamp: 1774772999, change: EventComplete(EventComplete), users_to_notify: [] } 1774772999.077 S -> bar: :My.Little.Server 001 bar :Welcome to the test Internet Relay Chat network, bar 1774772999.077 bar -> S: PING foo 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.077228Z#x1B[0m #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 1774772999.077 sable_ircd #x1B[2m2026-03-29T08:29:59.077276Z#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" 1774772999.177 bar -> S: PING synchronize303.395437433 1774772999.177 S -> bar: :My.Little.Server 002 bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1774772999.177 S -> bar: :My.Little.Server 003 bar :This server was created 2026-03-29 08:29:59.076458811 UTC 1774772999.177 S -> bar: :My.Little.Server 004 bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1774772999.177 S -> bar: :My.Little.Server 005 bar 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 1774772999.177 S -> bar: :My.Little.Server 422 bar :MOTD File is missing 1774772999.177 S -> bar: :bar!username@localhost MODE bar + 1774772999.177 S -> bar: :My.Little.Server NOTICE bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1774772999.178 sable_ircd #x1B[2m2026-03-29T08:29:59.177576Z#x1B[0m #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 1774772999.178 sable_ircd #x1B[2m2026-03-29T08:29:59.177661Z#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 synchronize303.395437433" 1774773000.178 bar: waiting… 1774773000.182 sable_ircd #x1B[2m2026-03-29T08:30:00.182077Z#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 1774773000.183 S -> bar: :My.Little.Server PONG My.Little.Server :foo 1774773001.184 bar: waiting… 1774773001.188 sable_ircd #x1B[2m2026-03-29T08:30:01.187832Z#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 :synchronize303.395437433 1774773001.188 S -> bar: :My.Little.Server PONG My.Little.Server :synchronize303.395437433 1774773001.890 baz: connects to server. 1774773001.890 baz -> S: NICK baz 1774773001.890 baz -> S: USER username * * :Realname 1774773001.890 sable_ircd #x1B[2m2026-03-29T08:30:01.890120Z#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 } 1774773001.890 sable_ircd #x1B[2m2026-03-29T08:30:01.890286Z#x1B[0m #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 1774773001.890 sable_ircd #x1B[2m2026-03-29T08:30:01.890330Z#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 1774773001.890 sable_ircd #x1B[2m2026-03-29T08:30:01.890402Z#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 1774773001.891 sable_ircd #x1B[2m2026-03-29T08:30:01.890526Z#x1B[0m #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 1774773001.891 sable_ircd #x1B[2m2026-03-29T08:30:01.890568Z#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 baz" 1774773001.891 sable_ircd #x1B[2m2026-03-29T08:30:01.890721Z#x1B[0m #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 1774773001.891 S -> baz: :My.Little.Server NOTICE * :*** Looking up your hostname1774773001.891 sable_ircd #x1B[2m2026-03-29T08:30:01.890778Z#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 username * * :Realname" 1774773001.891 sable_ircd #x1B[2m2026-03-29T08:30:01.890874Z#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: 306, tv_nsec: 108746579 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(), realname: OnceLock(Realname("Realname")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1774773001.891 sable_ircd #x1B[2m2026-03-29T08:30:01.890970Z#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: 306, tv_nsec: 108746579 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("baz")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1774773001.891 sable_ircd #x1B[2m2026-03-29T08:30:01.891014Z#x1B[0m #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 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.891559Z#x1B[0m #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 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.891590Z#x1B[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")) 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.891621Z#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 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.891669Z#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: 306, tv_nsec: 108746579 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("baz")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.891717Z#x1B[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)) 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.891948Z#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(826010299336232963)), timestamp: 1774773001, clock: EventClock(1 => 826010287525072898), target: User(UserId(Snowflake(826010299336232962))), details: NewUser(NewUser { nickname: Nickname("baz"), 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(826010299336232963)), NewUserConnection { user: UserId(Snowflake(826010299336232962)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1774773001 })) }) }; event clock=EventClock(1 => 826010287525072898) my clock=EventClock(1 => 826010287525072898) 1774773001.892 S -> baz: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.892046Z#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 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.892349Z#x1B[0m #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(826010299336232963)), timestamp: 1774773001, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826010299336232962)), 1) }), users_to_notify: [] }) 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.892388Z#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(826010299336232963)), timestamp: 1774773001, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(826010299336232962)), 1) }), users_to_notify: [] } 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.892440Z#x1B[0m #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(826010299336232963)), timestamp: 1774773001, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826010299336232962)), 2), connection: UserConnectionId(Snowflake(826010299336232963)) }), users_to_notify: [] }) 1774773001.892 sable_ircd #x1B[2m2026-03-29T08:30:01.892465Z#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(826010299336232963)), timestamp: 1774773001, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(826010299336232962)), 2), connection: UserConnectionId(Snowflake(826010299336232963)) }), users_to_notify: [] } 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892513Z#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 baz :Welcome to the test Internet Relay Chat network, baz 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892539Z#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 baz :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892561Z#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 baz :This server was created 2026-03-29 08:30:01.892552702 UTC 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892575Z#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 baz My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892593Z#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 baz 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 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892611Z#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 baz :MOTD File is missing 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892651Z#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): :baz!username@localhost MODE baz + 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892679Z#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 baz :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892726Z#x1B[0m #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(826010299336232963)), timestamp: 1774773001, change: EventComplete(EventComplete), users_to_notify: [] }) 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.892748Z#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(826010299336232963)), timestamp: 1774773001, change: EventComplete(EventComplete), users_to_notify: [] } 1774773001.893 S -> baz: :My.Little.Server 001 baz :Welcome to the test Internet Relay Chat network, baz 1774773001.893 baz -> S: PING foo 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.893271Z#x1B[0m #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 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.893317Z#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 foo" 1774773001.893 sable_ircd #x1B[2m2026-03-29T08:30:01.893410Z#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 :foo 1774773001.993 baz -> S: PING synchronize306.211549612 1774773001.993 S -> baz: :My.Little.Server 002 baz :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1774773001.993 S -> baz: :My.Little.Server 003 baz :This server was created 2026-03-29 08:30:01.892552702 UTC 1774773001.993 S -> baz: :My.Little.Server 004 baz My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1774773001.993 S -> baz: :My.Little.Server 005 baz 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 1774773001.993 S -> baz: :My.Little.Server 422 baz :MOTD File is missing 1774773001.993 S -> baz: :baz!username@localhost MODE baz + 1774773001.993 S -> baz: :My.Little.Server NOTICE baz :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1774773001.994 S -> baz: :My.Little.Server PONG My.Little.Server :foo 1774773001.994 sable_ircd #x1B[2m2026-03-29T08:30:01.993946Z#x1B[0m #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 1774773001.994 sable_ircd #x1B[2m2026-03-29T08:30:01.994035Z#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 synchronize306.211549612" 1774773001.994 sable_ircd #x1B[2m2026-03-29T08:30:01.994220Z#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 :synchronize306.211549612 1774773001.995 S -> baz: :My.Little.Server PONG My.Little.Server :synchronize306.211549612 1774773002.696 bar -> S: JOIN #bar 1774773002.696 sable_ircd #x1B[2m2026-03-29T08:30:02.696181Z#x1B[0m #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 1774773002.696 sable_ircd #x1B[2m2026-03-29T08:30:02.696276Z#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"JOIN #bar" 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.696667Z#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(826010302712647684)), timestamp: 1774773002, clock: EventClock(1 => 826010299336232963), target: Channel(ChannelId(Snowflake(826010302712647684))), details: NewChannel(NewChannel { name: ChannelName("#bar"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 826010299336232963) my clock=EventClock(1 => 826010299336232963) 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.696837Z#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 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.696933Z#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(826010302712647685)), timestamp: 1774773002, clock: EventClock(1 => 826010302712647684), target: Membership(MembershipId(UserId(Snowflake(826010287525072896)), ChannelId(Snowflake(826010302712647684)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(826010302712647684)), user: UserId(Snowflake(826010287525072896)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 826010302712647684) my clock=EventClock(1 => 826010302712647684) 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.697062Z#x1B[0m #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 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.697098Z#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 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.697322Z#x1B[0m #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(826010302712647684)), timestamp: 1774773002, change: EventComplete(EventComplete), users_to_notify: [] }) 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.697358Z#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(826010302712647684)), timestamp: 1774773002, change: EventComplete(EventComplete), users_to_notify: [] } 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.697395Z#x1B[0m #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(826010302712647685)), timestamp: 1774773002, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826010287525072896)), ChannelId(Snowflake(826010302712647684))), user: HistoricUserId(UserId(Snowflake(826010287525072896)), 2) }), users_to_notify: [UserId(Snowflake(826010287525072896))] }) 1774773002.697 sable_ircd #x1B[2m2026-03-29T08:30:02.697420Z#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(826010302712647685)), timestamp: 1774773002, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826010287525072896)), ChannelId(Snowflake(826010302712647684))), user: HistoricUserId(UserId(Snowflake(826010287525072896)), 2) }), users_to_notify: [UserId(Snowflake(826010287525072896))] } 1774773002.698 sable_ircd #x1B[2m2026-03-29T08:30:02.697476Z#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): :bar!username@localhost JOIN #bar 1774773002.698 sable_ircd #x1B[2m2026-03-29T08:30:02.697512Z#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): :bar!username@localhost MODE #bar +o bar 1774773002.698 sable_ircd #x1B[2m2026-03-29T08:30:02.697553Z#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 353 bar = #bar :@bar 1774773002.698 sable_ircd #x1B[2m2026-03-29T08:30:02.697573Z#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 366 bar #bar :End of names list 1774773002.698 sable_ircd #x1B[2m2026-03-29T08:30:02.697603Z#x1B[0m #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(826010302712647685)), timestamp: 1774773002, change: EventComplete(EventComplete), users_to_notify: [] }) 1774773002.698 sable_ircd #x1B[2m2026-03-29T08:30:02.697623Z#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(826010302712647685)), timestamp: 1774773002, change: EventComplete(EventComplete), users_to_notify: [] } 1774773002.796 bar -> S: PING synchronize307.014164638 1774773002.796 S -> bar: :bar!username@localhost JOIN #bar 1774773002.796 S -> bar: :bar!username@localhost MODE #bar +o bar 1774773002.796 S -> bar: :My.Little.Server 353 bar = #bar :@bar 1774773002.796 S -> bar: :My.Little.Server 366 bar #bar :End of names list 1774773002.796 sable_ircd #x1B[2m2026-03-29T08:30:02.796238Z#x1B[0m #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 1774773002.796 sable_ircd #x1B[2m2026-03-29T08:30:02.796290Z#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 synchronize307.014164638" 1774773003.048 sable_ircd #x1B[2m2026-03-29T08:30:03.047962Z#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 :synchronize307.014164638 1774773003.048 S -> bar: :My.Little.Server PONG My.Little.Server :synchronize307.014164638 1774773003.048 baz -> S: JOIN #bar 1774773003.049 sable_ircd #x1B[2m2026-03-29T08:30:03.048825Z#x1B[0m #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 1774773003.049 sable_ircd #x1B[2m2026-03-29T08:30:03.048877Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"JOIN #bar" 1774773003.049 sable_ircd #x1B[2m2026-03-29T08:30:03.049157Z#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(826010304193236998)), timestamp: 1774773003, clock: EventClock(1 => 826010302712647685), target: Membership(MembershipId(UserId(Snowflake(826010299336232962)), ChannelId(Snowflake(826010302712647684)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(826010302712647684)), user: UserId(Snowflake(826010299336232962)), permissions: MembershipFlagSet(0) }) }; event clock=EventClock(1 => 826010302712647685) my clock=EventClock(1 => 826010302712647685) 1774773003.049 sable_ircd #x1B[2m2026-03-29T08:30:03.049312Z#x1B[0m #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 1774773003.049 sable_ircd #x1B[2m2026-03-29T08:30:03.049317Z#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 1774773003.050 sable_ircd #x1B[2m2026-03-29T08:30:03.049594Z#x1B[0m #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(826010304193236998)), timestamp: 1774773003, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826010299336232962)), ChannelId(Snowflake(826010302712647684))), user: HistoricUserId(UserId(Snowflake(826010299336232962)), 2) }), users_to_notify: [UserId(Snowflake(826010287525072896)), UserId(Snowflake(826010299336232962))] }) 1774773003.050 sable_ircd #x1B[2m2026-03-29T08:30:03.049654Z#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(826010304193236998)), timestamp: 1774773003, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826010299336232962)), ChannelId(Snowflake(826010302712647684))), user: HistoricUserId(UserId(Snowflake(826010299336232962)), 2) }), users_to_notify: [UserId(Snowflake(826010287525072896)), UserId(Snowflake(826010299336232962))] } 1774773003.050 sable_ircd #x1B[2m2026-03-29T08:30:03.049716Z#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): :baz!username@localhost JOIN #bar 1774773003.050 sable_ircd #x1B[2m2026-03-29T08:30:03.049753Z#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): :baz!username@localhost JOIN #bar 1774773003.050 sable_ircd #x1B[2m2026-03-29T08:30:03.049796Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 353 baz = #bar :@bar baz 1774773003.050 sable_ircd #x1B[2m2026-03-29T08:30:03.049816Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 366 baz #bar :End of names list 1774773003.050 sable_ircd #x1B[2m2026-03-29T08:30:03.049861Z#x1B[0m #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(826010304193236998)), timestamp: 1774773003, change: EventComplete(EventComplete), users_to_notify: [] }) 1774773003.050 sable_ircd #x1B[2m2026-03-29T08:30:03.049884Z#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(826010304193236998)), timestamp: 1774773003, change: EventComplete(EventComplete), users_to_notify: [] } 1774773003.149 baz -> S: PING synchronize307.367004196 1774773003.149 S -> baz: :baz!username@localhost JOIN #bar 1774773003.149 S -> baz: :My.Little.Server 353 baz = #bar :@bar baz 1774773003.149 S -> baz: :My.Little.Server 366 baz #bar :End of names list 1774773003.149 sable_ircd #x1B[2m2026-03-29T08:30:03.149065Z#x1B[0m #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 1774773003.149 sable_ircd #x1B[2m2026-03-29T08:30:03.149121Z#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 synchronize307.367004196" 1774773003.149 sable_ircd #x1B[2m2026-03-29T08:30:03.149224Z#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 :synchronize307.367004196 1774773003.149 S -> baz: :My.Little.Server PONG My.Little.Server :synchronize307.367004196 1774773003.250 bar -> S: PING synchronize307.468001455 1774773003.250 S -> bar: :baz!username@localhost JOIN #bar 1774773003.250 sable_ircd #x1B[2m2026-03-29T08:30:03.250011Z#x1B[0m #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 1774773003.250 sable_ircd #x1B[2m2026-03-29T08:30:03.250063Z#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 synchronize307.468001455" 1774773004.004 sable_ircd #x1B[2m2026-03-29T08:30:04.004026Z#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 :synchronize307.468001455 1774773004.005 S -> bar: :My.Little.Server PONG My.Little.Server :synchronize307.468001455 1774773004.105 baz -> S: PING synchronize308.323081344 1774773004.105 sable_ircd #x1B[2m2026-03-29T08:30:04.105286Z#x1B[0m #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 1774773004.105 sable_ircd #x1B[2m2026-03-29T08:30:04.105351Z#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 synchronize308.323081344" 1774773004.106 sable_ircd #x1B[2m2026-03-29T08:30:04.105484Z#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 :synchronize308.323081344 1774773004.106 S -> baz: :My.Little.Server PONG My.Little.Server :synchronize308.323081344 1774773004.106 bar -> S: RENAME #bar #qux :no reason 1774773004.106 sable_ircd #x1B[2m2026-03-29T08:30:04.106059Z#x1B[0m #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 1774773004.106 sable_ircd #x1B[2m2026-03-29T08:30:04.106096Z#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"RENAME #bar #qux :no reason" 1774773004.206 bar -> S: PING synchronize308.42434429 1774773004.206 sable_ircd #x1B[2m2026-03-29T08:30:04.206340Z#x1B[0m #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 1774773004.206 sable_ircd #x1B[2m2026-03-29T08:30:04.206384Z#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 synchronize308.42434429" 1774773005.207 bar: waiting… 1774773005.211 sable_ircd #x1B[2m2026-03-29T08:30:05.211103Z#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(826010313261322247)), timestamp: 1774773005, clock: EventClock(1 => 826010304193236998), target: Channel(ChannelId(Snowflake(826010302712647684))), details: ChannelRename(ChannelRename { source: UserId(Snowflake(826010287525072896)), new_name: ChannelName("#qux"), message: Some("no reason") }) }; event clock=EventClock(1 => 826010304193236998) my clock=EventClock(1 => 826010304193236998) 1774773005.211 sable_ircd #x1B[2m2026-03-29T08:30:05.211260Z#x1B[0m #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 1774773005.211 sable_ircd #x1B[2m2026-03-29T08:30:05.211279Z#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 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211535Z#x1B[0m #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(826010313261322247)), timestamp: 1774773005, change: ChannelRename(ChannelRename { source: User(HistoricUserId(UserId(Snowflake(826010287525072896)), 2)), channel: ChannelId(Snowflake(826010302712647684)), old_name: ChannelName("#bar"), new_name: ChannelName("#qux"), message: "no reason" }), users_to_notify: [UserId(Snowflake(826010287525072896)), UserId(Snowflake(826010299336232962))] }) 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211577Z#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(826010313261322247)), timestamp: 1774773005, change: ChannelRename(ChannelRename { source: User(HistoricUserId(UserId(Snowflake(826010287525072896)), 2)), channel: ChannelId(Snowflake(826010302712647684)), old_name: ChannelName("#bar"), new_name: ChannelName("#qux"), message: "no reason" }), users_to_notify: [UserId(Snowflake(826010287525072896)), UserId(Snowflake(826010299336232962))] } 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211674Z#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): :bar!username@localhost RENAME #bar #qux :no reason 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211726Z#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): :baz!username@localhost PART #bar :no reason 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211757Z#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): :baz!username@localhost JOIN #qux 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211795Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 353 baz = #qux :@bar baz 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211815Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 366 baz #qux :End of names list 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211872Z#x1B[0m #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(826010313261322247)), timestamp: 1774773005, change: EventComplete(EventComplete), users_to_notify: [] }) 1774773005.212 sable_ircd #x1B[2m2026-03-29T08:30:05.211895Z#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(826010313261322247)), timestamp: 1774773005, change: EventComplete(EventComplete), users_to_notify: [] } 1774773005.212 S -> bar: :bar!username@localhost RENAME #bar #qux :no reason 1774773006.213 bar: waiting… 1774773006.218 sable_ircd #x1B[2m2026-03-29T08:30:06.217483Z#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 :synchronize308.42434429 1774773006.218 S -> bar: :My.Little.Server PONG My.Little.Server :synchronize308.42434429 1774773006.318 baz -> S: PING synchronize310.536568136 1774773006.318 S -> baz: :baz!username@localhost PART #bar :no reason 1774773006.318 S -> baz: :baz!username@localhost JOIN #qux 1774773006.318 S -> baz: :My.Little.Server 353 baz = #qux :@bar baz 1774773006.318 S -> baz: :My.Little.Server 366 baz #qux :End of names list 1774773006.319 sable_ircd #x1B[2m2026-03-29T08:30:06.318691Z#x1B[0m #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 1774773006.319 sable_ircd #x1B[2m2026-03-29T08:30:06.318760Z#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 synchronize310.536568136" 1774773006.319 sable_ircd #x1B[2m2026-03-29T08:30:06.318911Z#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 :synchronize310.536568136 1774773006.319 S -> baz: :My.Little.Server PONG My.Little.Server :synchronize310.536568136 1774773006.319 baz -> S: JOIN #bar 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.319537Z#x1B[0m #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 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.319584Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"JOIN #bar" 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.319842Z#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(826010317908611080)), timestamp: 1774773006, clock: EventClock(1 => 826010313261322247), target: Channel(ChannelId(Snowflake(826010317908611077))), details: NewChannel(NewChannel { name: ChannelName("#bar"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 826010313261322247) my clock=EventClock(1 => 826010313261322247) 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.319989Z#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 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.320082Z#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(826010317912805385)), timestamp: 1774773006, clock: EventClock(1 => 826010317908611080), target: Membership(MembershipId(UserId(Snowflake(826010299336232962)), ChannelId(Snowflake(826010317908611077)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(826010317908611077)), user: UserId(Snowflake(826010299336232962)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 826010317908611080) my clock=EventClock(1 => 826010317908611080) 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.320212Z#x1B[0m #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 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.320243Z#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 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.320318Z#x1B[0m #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(826010317908611080)), timestamp: 1774773006, change: EventComplete(EventComplete), users_to_notify: [] }) 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.320352Z#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(826010317908611080)), timestamp: 1774773006, change: EventComplete(EventComplete), users_to_notify: [] } 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.320417Z#x1B[0m #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(826010317912805385)), timestamp: 1774773006, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826010299336232962)), ChannelId(Snowflake(826010317908611077))), user: HistoricUserId(UserId(Snowflake(826010299336232962)), 2) }), users_to_notify: [UserId(Snowflake(826010299336232962))] }) 1774773006.320 sable_ircd #x1B[2m2026-03-29T08:30:06.320460Z#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(826010317912805385)), timestamp: 1774773006, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(826010299336232962)), ChannelId(Snowflake(826010317908611077))), user: HistoricUserId(UserId(Snowflake(826010299336232962)), 2) }), users_to_notify: [UserId(Snowflake(826010299336232962))] } 1774773006.321 sable_ircd #x1B[2m2026-03-29T08:30:06.320513Z#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): :baz!username@localhost JOIN #bar 1774773006.321 sable_ircd #x1B[2m2026-03-29T08:30:06.320549Z#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): :baz!username@localhost MODE #bar +o baz 1774773006.321 sable_ircd #x1B[2m2026-03-29T08:30:06.320589Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 353 baz = #bar :@baz 1774773006.321 sable_ircd #x1B[2m2026-03-29T08:30:06.320612Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 366 baz #bar :End of names list 1774773006.321 sable_ircd #x1B[2m2026-03-29T08:30:06.320705Z#x1B[0m #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(826010317912805385)), timestamp: 1774773006, change: EventComplete(EventComplete), users_to_notify: [] }) 1774773006.321 sable_ircd #x1B[2m2026-03-29T08:30:06.320733Z#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(826010317912805385)), timestamp: 1774773006, change: EventComplete(EventComplete), users_to_notify: [] } 1774773006.420 baz -> S: PING synchronize310.637844474 1774773006.420 S -> baz: :baz!username@localhost JOIN #bar 1774773006.420 S -> baz: :baz!username@localhost MODE #bar +o baz 1774773006.420 S -> baz: :My.Little.Server 353 baz = #bar :@baz 1774773006.420 S -> baz: :My.Little.Server 366 baz #bar :End of names list 1774773006.420 sable_ircd #x1B[2m2026-03-29T08:30:06.419948Z#x1B[0m #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 1774773006.420 sable_ircd #x1B[2m2026-03-29T08:30:06.420025Z#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 synchronize310.637844474" 1774773007.174 sable_ircd #x1B[2m2026-03-29T08:30:07.173826Z#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 :synchronize310.637844474 1774773007.174 S -> baz: :My.Little.Server PONG My.Little.Server :synchronize310.637844474 1774773007.174 baz -> S: MODE #bar +k beer 1774773007.175 sable_ircd #x1B[2m2026-03-29T08:30:07.174522Z#x1B[0m #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 1774773007.175 sable_ircd #x1B[2m2026-03-29T08:30:07.174573Z#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"MODE #bar +k beer" 1774773007.274 baz -> S: PING synchronize311.492760834 1774773007.275 sable_ircd #x1B[2m2026-03-29T08:30:07.274910Z#x1B[0m #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 1774773007.275 sable_ircd #x1B[2m2026-03-29T08:30:07.274983Z#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 synchronize311.492760834" 1774773008.029 sable_ircd #x1B[2m2026-03-29T08:30:08.029201Z#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(826010325080870922)), timestamp: 1774773008, clock: EventClock(1 => 826010317912805385), target: Channel(ChannelId(Snowflake(826010317908611077))), details: ChannelModeChange(ChannelModeChange { changed_by: User(UserId(Snowflake(826010299336232962))), added: ChannelModeSet(0), removed: ChannelModeSet(0), key_change: Set(ChannelKey("beer")) }) }; event clock=EventClock(1 => 826010317912805385) my clock=EventClock(1 => 826010317912805385) 1774773008.029 sable_ircd #x1B[2m2026-03-29T08:30:08.029396Z#x1B[0m #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 1774773008.029 sable_ircd #x1B[2m2026-03-29T08:30:08.029396Z#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 1774773008.030 sable_ircd #x1B[2m2026-03-29T08:30:08.029751Z#x1B[0m #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(826010325080870922)), timestamp: 1774773008, change: ChannelModeChange(ChannelModeChange { channel: ChannelId(Snowflake(826010317908611077)), added: ChannelModeSet(0), removed: ChannelModeSet(0), key_change: Set(ChannelKey("beer")), changed_by: User(HistoricUserId(UserId(Snowflake(826010299336232962)), 2)) }), users_to_notify: [UserId(Snowflake(826010299336232962))] }) 1774773008.030 sable_ircd #x1B[2m2026-03-29T08:30:08.029804Z#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(826010325080870922)), timestamp: 1774773008, change: ChannelModeChange(ChannelModeChange { channel: ChannelId(Snowflake(826010317908611077)), added: ChannelModeSet(0), removed: ChannelModeSet(0), key_change: Set(ChannelKey("beer")), changed_by: User(HistoricUserId(UserId(Snowflake(826010299336232962)), 2)) }), users_to_notify: [UserId(Snowflake(826010299336232962))] } 1774773008.030 sable_ircd #x1B[2m2026-03-29T08:30:08.029873Z#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): :baz!username@localhost MODE #bar +k beer 1774773008.030 sable_ircd #x1B[2m2026-03-29T08:30:08.029937Z#x1B[0m #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(826010325080870922)), timestamp: 1774773008, change: EventComplete(EventComplete), users_to_notify: [] }) 1774773008.030 sable_ircd #x1B[2m2026-03-29T08:30:08.029964Z#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(826010325080870922)), timestamp: 1774773008, change: EventComplete(EventComplete), users_to_notify: [] } 1774773008.030 S -> baz: :baz!username@localhost MODE #bar +k beer 1774773009.032 baz: waiting… 1774773009.037 sable_ircd #x1B[2m2026-03-29T08:30:09.036535Z#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 :synchronize311.492760834 1774773009.037 S -> baz: :My.Little.Server PONG My.Little.Server :synchronize311.492760834 1774773009.040 bar: disconnects from server. 1774773009.040 baz: disconnects from server.