--------------------------------- Captured Out --------------------------------- ---- new test ---- 1786476538.252 sable_ircd uid=1001 1786476538.256 sable_ircd #x1B[2m2026-08-11T19:28:58.255863Z#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(875098488721903616)), timestamp: 1786476538, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1786476538, name: ServerName("My.Little.Server"), ts: 1786476538, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock() 1786476538.256 sable_ircd #x1B[2m2026-08-11T19:28:58.256016Z#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 1786476538.256 sable_ircd #x1B[2m2026-08-11T19:28:58.256109Z#x1B[0m #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 1786476538.256 sable_ircd #x1B[2m2026-08-11T19:28:58.256140Z#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(875098488726097921)), timestamp: 1786476538, clock: EventClock(1 => 875098488721903616), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1786476538 }) }; event clock=EventClock(1 => 875098488721903616) my clock=EventClock(1 => 875098488721903616) 1786476538.256 sable_ircd #x1B[2m2026-08-11T19:28:58.256200Z#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 1786476538.256 sable_ircd #x1B[2m2026-08-11T19:28:58.256267Z#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(875098488721903616)), timestamp: 1786476538, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1786476538, name: ServerName("My.Little.Server"), ts: 1786476538, 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 1786476538.257 sable_ircd #x1B[2m2026-08-11T19:28:58.256456Z#x1B[0m #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(875098488721903616)), timestamp: 1786476538, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }) 1786476538.257 sable_ircd #x1B[2m2026-08-11T19:28:58.256528Z#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(875098488721903616)), timestamp: 1786476538, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] } 1786476538.257 sable_ircd #x1B[2m2026-08-11T19:28:58.256602Z#x1B[0m #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(875098488721903616)), timestamp: 1786476538, change: EventComplete(EventComplete), users_to_notify: [] }) 1786476538.257 sable_ircd #x1B[2m2026-08-11T19:28:58.256644Z#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(875098488721903616)), timestamp: 1786476538, change: EventComplete(EventComplete), users_to_notify: [] } 1786476538.257 sable_ircd #x1B[2m2026-08-11T19:28:58.256714Z#x1B[0m #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(875098488726097921)), timestamp: 1786476538, change: EventComplete(EventComplete), users_to_notify: [] }) 1786476538.257 sable_ircd #x1B[2m2026-08-11T19:28:58.256770Z#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(875098488726097921)), timestamp: 1786476538, change: EventComplete(EventComplete), users_to_notify: [] } 1786476538.348 sable_ircd #x1B[2m2026-08-11T19:28:58.348426Z#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 } 1786476538.349 sable_ircd #x1B[2m2026-08-11T19:28:58.348612Z#x1B[0m #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 1786476538.349 sable_ircd #x1B[2m2026-08-11T19:28:58.348660Z#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 1786476538.349 sable_ircd #x1B[2m2026-08-11T19:28:58.348728Z#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 1786476538.349 sable_ircd #x1B[2m2026-08-11T19:28:58.348877Z#x1B[0m #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 1786476538.349 sable_ircd #x1B[2m2026-08-11T19:28:58.348948Z#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" 1786476538.349 sable_ircd #x1B[2m2026-08-11T19:28:58.349097Z#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 1786476538.349 sable_ircd #x1B[2m2026-08-11T19:28:58.349202Z#x1B[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)) 1786476538.349 sable_ircd #x1B[2m2026-08-11T19:28:58.349252Z#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) 1786476538.350 sable_ircd #x1B[2m2026-08-11T19:28:58.349753Z#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 1786476538.350 sable_ircd #x1B[2m2026-08-11T19:28:58.349855Z#x1B[0m #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 1786476538.350 sable_ircd #x1B[2m2026-08-11T19:28:58.350058Z#x1B[0m #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 1786476538.369 1: connects to server. 1786476538.370 1 -> S: NICK foo 1786476538.370 1 -> S: USER username * * :Realname 1786476538.370 sable_ircd #x1B[2m2026-08-11T19:28:58.369852Z#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 } 1786476538.370 sable_ircd #x1B[2m2026-08-11T19:28:58.370006Z#x1B[0m #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 1786476538.370 sable_ircd #x1B[2m2026-08-11T19:28:58.370069Z#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 1786476538.370 sable_ircd #x1B[2m2026-08-11T19:28:58.370142Z#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 1786476538.370 sable_ircd #x1B[2m2026-08-11T19:28:58.370331Z#x1B[0m #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 1786476538.370 sable_ircd #x1B[2m2026-08-11T19:28:58.370409Z#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" 1786476538.371 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname 1786476538.371 sable_ircd #x1B[2m2026-08-11T19:28:58.370674Z#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: 315, tv_nsec: 430620080 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("foo")), realname: OnceLock(), 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 1786476538.371 sable_ircd #x1B[2m2026-08-11T19:28:58.370757Z#x1B[0m #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 1786476538.371 sable_ircd #x1B[2m2026-08-11T19:28:58.370805Z#x1B[0m #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 1786476538.371 sable_ircd #x1B[2m2026-08-11T19:28:58.370849Z#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" 1786476538.371 sable_ircd #x1B[2m2026-08-11T19:28:58.370926Z#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: 315, tv_nsec: 430620080 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("foo")), 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 1786476538.371 sable_ircd #x1B[2m2026-08-11T19:28:58.371423Z#x1B[0m #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 1786476538.371 sable_ircd #x1B[2m2026-08-11T19:28:58.371475Z#x1B[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")) 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.371501Z#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 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.371561Z#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: 315, tv_nsec: 430620080 }, 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[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.371613Z#x1B[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)) 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.371877Z#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(875098489208442882)), timestamp: 1786476538, clock: EventClock(1 => 875098488726097921), target: User(UserId(Snowflake(875098489208442880))), 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(875098489208442881)), NewUserConnection { user: UserId(Snowflake(875098489208442880)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1786476538 })) }) }; event clock=EventClock(1 => 875098488726097921) my clock=EventClock(1 => 875098488726097921) 1786476538.372 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.371988Z#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 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.372283Z#x1B[0m #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(875098489208442882)), timestamp: 1786476538, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(875098489208442880)), 1) }), users_to_notify: [] }) 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.372327Z#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(875098489208442882)), timestamp: 1786476538, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(875098489208442880)), 1) }), users_to_notify: [] } 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.372385Z#x1B[0m #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(875098489208442882)), timestamp: 1786476538, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(875098489208442880)), 2), connection: UserConnectionId(Snowflake(875098489208442881)) }), users_to_notify: [] }) 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.372410Z#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(875098489208442882)), timestamp: 1786476538, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(875098489208442880)), 2), connection: UserConnectionId(Snowflake(875098489208442881)) }), users_to_notify: [] } 1786476538.372 sable_ircd #x1B[2m2026-08-11T19:28:58.372461Z#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 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372489Z#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 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372544Z#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-08-11 19:28:58.372530840 UTC 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372569Z#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 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372602Z#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 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372621Z#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 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372640Z#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 + 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372655Z#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. 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372688Z#x1B[0m #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(875098489208442882)), timestamp: 1786476538, change: EventComplete(EventComplete), users_to_notify: [] }) 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.372708Z#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(875098489208442882)), timestamp: 1786476538, change: EventComplete(EventComplete), users_to_notify: [] } 1786476538.373 S -> 1: :My.Little.Server 001 foo :Welcome to the test Internet Relay Chat network, foo 1786476538.373 1 -> S: PING foo 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.373201Z#x1B[0m #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 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.373251Z#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" 1786476538.373 sable_ircd #x1B[2m2026-08-11T19:28:58.373348Z#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 1786476538.436 sable_history #x1B[2m2027-08-11T20:21:25.927817Z#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(1007383261959364668)), timestamp: 1818015685, clock: EventClock(1 => 1007369621558267926, 50 => 1007383010301124667, 99 => 1007369624859586564), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015685 }) }; event clock=EventClock(1 => 1007369621558267926, 50 => 1007383010301124667, 99 => 1007369624859586564) my clock=EventClock(1 => 1007369621558267926, 50 => 1007383010301124667, 99 => 1007369624859586564) 1786476538.436 sable_history #x1B[2m2027-08-11T20:21:25.929449Z#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 1786476538.473 1 -> S: PING synchronize315.533625772 1786476538.473 S -> 1: :My.Little.Server 002 foo :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1786476538.473 S -> 1: :My.Little.Server 003 foo :This server was created 2026-08-11 19:28:58.372530840 UTC 1786476538.473 S -> 1: :My.Little.Server 004 foo My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1786476538.473 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 1786476538.473 S -> 1: :My.Little.Server 422 foo :MOTD File is missing 1786476538.473 S -> 1: :foo!username@localhost MODE foo + 1786476538.473 S -> 1: :My.Little.Server NOTICE foo :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1786476538.473 S -> 1: :My.Little.Server PONG My.Little.Server :foo 1786476538.474 sable_ircd #x1B[2m2026-08-11T19:28:58.473777Z#x1B[0m #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 1786476538.474 sable_ircd #x1B[2m2026-08-11T19:28:58.473866Z#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 synchronize315.533625772" 1786476538.474 sable_ircd #x1B[2m2026-08-11T19:28:58.474063Z#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 :synchronize315.533625772 1786476538.474 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize315.533625772 1786476538.479 sable_history #x1B[2m2027-08-11T20:10:05.418406Z#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(1007380407693549616)), timestamp: 1818015005, clock: EventClock(1 => 1007369401122426895, 50 => 1007380156035309615, 99 => 1007369391161356292), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015005 }) }; event clock=EventClock(1 => 1007369401122426895, 50 => 1007380156035309615, 99 => 1007369391161356292) my clock=EventClock(1 => 1007369401122426895, 50 => 1007380156035309615, 99 => 1007369391161356292) 1786476538.479 sable_history #x1B[2m2027-08-11T20:10:05.419898Z#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 1786476539.175 2: connects to server. 1786476539.176 2 -> S: NICK bar 1786476539.176 2 -> S: USER username * * :Realname 1786476539.176 sable_ircd #x1B[2m2026-08-11T19:28:59.176081Z#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 } 1786476539.176 sable_ircd #x1B[2m2026-08-11T19:28:59.176250Z#x1B[0m #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 1786476539.176 sable_ircd #x1B[2m2026-08-11T19:28:59.176322Z#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 1786476539.176 sable_ircd #x1B[2m2026-08-11T19:28:59.176408Z#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 1786476539.177 S -> 2: :My.Little.Server NOTICE * :*** Looking up your hostname 1786476539.177 sable_ircd #x1B[2m2026-08-11T19:28:59.176643Z#x1B[0m #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 1786476539.177 sable_ircd #x1B[2m2026-08-11T19:28:59.176708Z#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 bar" 1786476539.177 sable_ircd #x1B[2m2026-08-11T19:28:59.176868Z#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: 316, tv_nsec: 236876995 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("bar")), realname: OnceLock(), 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 1786476539.177 sable_ircd #x1B[2m2026-08-11T19:28:59.176939Z#x1B[0m #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 1786476539.177 sable_ircd #x1B[2m2026-08-11T19:28:59.176975Z#x1B[0m #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 1786476539.177 sable_ircd #x1B[2m2026-08-11T19:28:59.177015Z#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" 1786476539.177 sable_ircd #x1B[2m2026-08-11T19:28:59.177112Z#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: 316, tv_nsec: 236876995 }, attach_user_id: OnceLock(), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("bar")), 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 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.177841Z#x1B[0m #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 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.177881Z#x1B[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")) 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.177920Z#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 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.177976Z#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: 316, tv_nsec: 236876995 }, 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[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.178041Z#x1B[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)) 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.178328Z#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(875098492593246211)), timestamp: 1786476539, clock: EventClock(1 => 875098489208442882), target: User(UserId(Snowflake(875098492593246210))), 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(875098492593246211)), NewUserConnection { user: UserId(Snowflake(875098492593246210)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1786476539 })) }) }; event clock=EventClock(1 => 875098489208442882) my clock=EventClock(1 => 875098489208442882) 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.178419Z#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 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.178733Z#x1B[0m #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(875098492593246211)), timestamp: 1786476539, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(875098492593246210)), 1) }), users_to_notify: [] }) 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.178772Z#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(875098492593246211)), timestamp: 1786476539, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(875098492593246210)), 1) }), users_to_notify: [] } 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.178849Z#x1B[0m #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(875098492593246211)), timestamp: 1786476539, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(875098492593246210)), 2), connection: UserConnectionId(Snowflake(875098492593246211)) }), users_to_notify: [] }) 1786476539.179 sable_ircd #x1B[2m2026-08-11T19:28:59.178923Z#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(875098492593246211)), timestamp: 1786476539, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(875098492593246210)), 2), connection: UserConnectionId(Snowflake(875098492593246211)) }), users_to_notify: [] } 1786476539.179 S -> 2: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179001Z#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 bar :Welcome to the test Internet Relay Chat network, bar 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179052Z#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 bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179092Z#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 bar :This server was created 2026-08-11 19:28:59.179077697 UTC 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179121Z#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 bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179151Z#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 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 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179181Z#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 bar :MOTD File is missing 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179214Z#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): :bar!username@localhost MODE bar + 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179245Z#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 bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179311Z#x1B[0m #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(875098492593246211)), timestamp: 1786476539, change: EventComplete(EventComplete), users_to_notify: [] }) 1786476539.180 sable_ircd #x1B[2m2026-08-11T19:28:59.179343Z#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(875098492593246211)), timestamp: 1786476539, change: EventComplete(EventComplete), users_to_notify: [] } 1786476539.180 S -> 2: :My.Little.Server 001 bar :Welcome to the test Internet Relay Chat network, bar 1786476539.180 S -> 2: :My.Little.Server 002 bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1786476539.180 S -> 2: :My.Little.Server 003 bar :This server was created 2026-08-11 19:28:59.179077697 UTC 1786476539.180 S -> 2: :My.Little.Server 004 bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1786476539.180 S -> 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 1786476539.180 S -> 2: :My.Little.Server 422 bar :MOTD File is missing 1786476539.180 S -> 2: :bar!username@localhost MODE bar + 1786476539.180 S -> 2: :My.Little.Server NOTICE bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1786476539.180 2 -> S: PING foo 1786476539.181 sable_ircd #x1B[2m2026-08-11T19:28:59.180492Z#x1B[0m #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 1786476539.181 sable_ircd #x1B[2m2026-08-11T19:28:59.180665Z#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" 1786476539.181 sable_ircd #x1B[2m2026-08-11T19:28:59.180783Z#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 1786476539.864 sable_history #x1B[2m2027-08-11T20:13:51.132509Z#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(1007381354406682676)), timestamp: 1818015231, clock: EventClock(1 => 1007370015864786977, 50 => 1007381102752636979, 99 => 1007370023867920389), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015231 }) }; event clock=EventClock(1 => 1007370015864786977, 50 => 1007381102752636979, 99 => 1007370023867920389) my clock=EventClock(1 => 1007370015864786977, 50 => 1007381102752636979, 99 => 1007370023867920389) 1786476539.864 sable_history #x1B[2m2027-08-11T20:13:51.134231Z#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 1786476539.981 2 -> S: PING synchronize317.041725358 1786476539.981 S -> 2: :My.Little.Server PONG My.Little.Server :foo 1786476539.982 sable_ircd #x1B[2m2026-08-11T19:28:59.981829Z#x1B[0m #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 1786476539.982 sable_ircd #x1B[2m2026-08-11T19:28:59.981901Z#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 synchronize317.041725358" 1786476539.982 sable_ircd #x1B[2m2026-08-11T19:28:59.982049Z#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 :synchronize317.041725358 1786476539.982 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize317.041725358 1786476540.060 sable_history #x1B[2m2027-08-11T20:11:02.835512Z#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(1007380648517902384)), timestamp: 1818015062, clock: EventClock(1 => 1007369312341594113, 50 => 1007380396863856687), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015062 }) }; event clock=EventClock(1 => 1007369312341594113, 50 => 1007380396863856687) my clock=EventClock(1 => 1007369312341594113, 50 => 1007380396863856687) 1786476540.060 sable_history #x1B[2m2027-08-11T20:11:02.837509Z#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 1786476540.083 1 -> S: PING synchronize317.143056424 1786476540.083 sable_ircd #x1B[2m2026-08-11T19:29:00.083215Z#x1B[0m #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 1786476540.083 sable_ircd #x1B[2m2026-08-11T19:29:00.083302Z#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 synchronize317.143056424" 1786476540.084 sable_ircd #x1B[2m2026-08-11T19:29:00.083489Z#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 :synchronize317.143056424 1786476540.084 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize317.143056424 1786476540.184 2 -> S: PING synchronize317.244546895 1786476540.185 sable_ircd #x1B[2m2026-08-11T19:29:00.184674Z#x1B[0m #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 1786476540.185 sable_ircd #x1B[2m2026-08-11T19:29:00.184749Z#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 synchronize317.244546895" 1786476540.185 sable_ircd #x1B[2m2026-08-11T19:29:00.184891Z#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 :synchronize317.244546895 1786476540.185 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize317.244546895 1786476540.185 1 -> S: JOIN #chan 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.185616Z#x1B[0m #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 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.185656Z#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 #chan" 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.185916Z#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(875098496816910340)), timestamp: 1786476540, clock: EventClock(1 => 875098492593246211), target: Channel(ChannelId(Snowflake(875098496816910340))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 875098492593246211) my clock=EventClock(1 => 875098492593246211) 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.186076Z#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 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.186168Z#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(875098496821104645)), timestamp: 1786476540, clock: EventClock(1 => 875098496816910340), target: Membership(MembershipId(UserId(Snowflake(875098489208442880)), ChannelId(Snowflake(875098496816910340)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(875098496816910340)), user: UserId(Snowflake(875098489208442880)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 875098496816910340) my clock=EventClock(1 => 875098496816910340) 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.186309Z#x1B[0m #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 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.186338Z#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 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.186387Z#x1B[0m #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(875098496816910340)), timestamp: 1786476540, change: EventComplete(EventComplete), users_to_notify: [] }) 1786476540.186 sable_ircd #x1B[2m2026-08-11T19:29:00.186422Z#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(875098496816910340)), timestamp: 1786476540, change: EventComplete(EventComplete), users_to_notify: [] } 1786476540.187 sable_ircd #x1B[2m2026-08-11T19:29:00.186562Z#x1B[0m #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(875098496821104645)), timestamp: 1786476540, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(875098489208442880)), ChannelId(Snowflake(875098496816910340))), user: HistoricUserId(UserId(Snowflake(875098489208442880)), 2) }), users_to_notify: [UserId(Snowflake(875098489208442880))] }) 1786476540.187 sable_ircd #x1B[2m2026-08-11T19:29:00.186614Z#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(875098496821104645)), timestamp: 1786476540, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(875098489208442880)), ChannelId(Snowflake(875098496816910340))), user: HistoricUserId(UserId(Snowflake(875098489208442880)), 2) }), users_to_notify: [UserId(Snowflake(875098489208442880))] } 1786476540.187 sable_ircd #x1B[2m2026-08-11T19:29:00.186692Z#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 JOIN #chan 1786476540.187 sable_ircd #x1B[2m2026-08-11T19:29:00.186746Z#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 #chan +o foo 1786476540.187 sable_ircd #x1B[2m2026-08-11T19:29:00.186819Z#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 foo = #chan :@foo 1786476540.187 sable_ircd #x1B[2m2026-08-11T19:29:00.186865Z#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 foo #chan :End of names list 1786476540.187 sable_ircd #x1B[2m2026-08-11T19:29:00.186939Z#x1B[0m #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(875098496821104645)), timestamp: 1786476540, change: EventComplete(EventComplete), users_to_notify: [] }) 1786476540.187 sable_ircd #x1B[2m2026-08-11T19:29:00.186973Z#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(875098496821104645)), timestamp: 1786476540, change: EventComplete(EventComplete), users_to_notify: [] } 1786476540.285 1 -> S: PING synchronize317.345936663 1786476540.286 S -> 1: :foo!username@localhost JOIN #chan 1786476540.286 S -> 1: :foo!username@localhost MODE #chan +o foo 1786476540.286 S -> 1: :My.Little.Server 353 foo = #chan :@foo 1786476540.286 S -> 1: :My.Little.Server 366 foo #chan :End of names list 1786476540.286 sable_ircd #x1B[2m2026-08-11T19:29:00.286050Z#x1B[0m #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 1786476540.286 sable_ircd #x1B[2m2026-08-11T19:29:00.286132Z#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 synchronize317.345936663" 1786476540.560 sable_history #x1B[2m2027-08-11T20:11:03.442644Z#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(1007380651063844912)), timestamp: 1818015063, clock: EventClock(1 => 1007369314333888513, 50 => 1007380399405604911), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015063 }) }; event clock=EventClock(1 => 1007369314333888513, 50 => 1007380399405604911) my clock=EventClock(1 => 1007369314333888513, 50 => 1007380399405604911) 1786476540.560 sable_history #x1B[2m2027-08-11T20:11:03.444655Z#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 1786476540.876 sable_history #x1B[2m2027-08-11T20:16:39.718111Z#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(1007382061507616823)), timestamp: 1818015399, clock: EventClock(1 => 1007369718299889686, 50 => 1007381809849376822, 99 => 1007369721508933636), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015399 }) }; event clock=EventClock(1 => 1007369718299889686, 50 => 1007381809849376822, 99 => 1007369721508933636) my clock=EventClock(1 => 1007369718299889686, 50 => 1007381809849376822, 99 => 1007369721508933636) 1786476540.876 sable_history #x1B[2m2027-08-11T20:16:39.719960Z#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 1786476541.040 sable_ircd #x1B[2m2026-08-11T19:29:01.040164Z#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 :synchronize317.345936663 1786476541.041 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize317.345936663 1786476541.041 1 -> S: INVITE bar #chan 1786476541.041 sable_history #x1B[2m2027-08-11T20:11:03.811548Z#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(1007380652611543088)), timestamp: 1818015063, clock: EventClock(1 => 1007369316242296833, 50 => 1007380400957497391), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015063 }) }; event clock=EventClock(1 => 1007369316242296833, 50 => 1007380400957497391) my clock=EventClock(1 => 1007369316242296833, 50 => 1007380400957497391) 1786476541.041 sable_history #x1B[2m2027-08-11T20:11:03.813888Z#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 1786476541.041 sable_ircd #x1B[2m2026-08-11T19:29:01.041134Z#x1B[0m #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 1786476541.041 sable_ircd #x1B[2m2026-08-11T19:29:01.041201Z#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"INVITE bar #chan" 1786476541.141 1 -> S: PING synchronize318.201404811 1786476541.141 sable_ircd #x1B[2m2026-08-11T19:29:01.141440Z#x1B[0m #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 1786476541.142 sable_ircd #x1B[2m2026-08-11T19:29:01.141528Z#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 synchronize318.201404811" 1786476541.536 sable_history #x1B[2m2027-08-11T20:19:28.354255Z#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(1007382768818266170)), timestamp: 1818015568, clock: EventClock(1 => 1007369670753259542, 50 => 1007382517164220473, 99 => 1007369673970692100), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015568 }) }; event clock=EventClock(1 => 1007369670753259542, 50 => 1007382517164220473, 99 => 1007369673970692100) my clock=EventClock(1 => 1007369670753259542, 50 => 1007382517164220473, 99 => 1007369673970692100) 1786476541.536 sable_history #x1B[2m2027-08-11T20:19:28.356057Z#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 1786476541.549 sable_history #x1B[2m2027-08-11T20:11:04.565384Z#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(1007380655774048304)), timestamp: 1818015064, clock: EventClock(1 => 1007369318356226049, 50 => 1007380404120002607), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015064 }) }; event clock=EventClock(1 => 1007369318356226049, 50 => 1007380404120002607) my clock=EventClock(1 => 1007369318356226049, 50 => 1007380404120002607) 1786476541.549 sable_history #x1B[2m2027-08-11T20:11:04.567304Z#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 1786476542.059 sable_history #x1B[2m2027-08-11T20:11:05.941155Z#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(1007380661541216305)), timestamp: 1818015065, clock: EventClock(1 => 1007369320184942593, 50 => 1007380409882976304, 99 => 1007369323750502402), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015065 }) }; event clock=EventClock(1 => 1007369320184942593, 50 => 1007380409882976304, 99 => 1007369323750502402) my clock=EventClock(1 => 1007369320184942593, 50 => 1007380409882976304, 99 => 1007369323750502402) 1786476542.059 sable_history #x1B[2m2027-08-11T20:11:05.943359Z#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 1786476542.142 1: waiting… 1786476542.146 sable_ircd #x1B[2m2026-08-11T19:29:02.146011Z#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 341 foo bar #chan 1786476542.146 sable_ircd #x1B[2m2026-08-11T19:29:02.146079Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mStateChange(Invite(InviteId(UserId(Snowflake(875098492593246210)), ChannelId(Snowflake(875098496816910340)))), ChannelInvite(ChannelInvite { source: UserId(Snowflake(875098489208442880)) })) 1786476542.147 S -> 1: :My.Little.Server 341 foo bar #chan 1786476542.147 sable_ircd #x1B[2m2026-08-11T19:29:02.146238Z#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(875098505041940486)), timestamp: 1786476542, clock: EventClock(1 => 875098496821104645), target: Invite(InviteId(UserId(Snowflake(875098492593246210)), ChannelId(Snowflake(875098496816910340)))), details: ChannelInvite(ChannelInvite { source: UserId(Snowflake(875098489208442880)) }) }; event clock=EventClock(1 => 875098496821104645) my clock=EventClock(1 => 875098496821104645) 1786476542.147 sable_ircd #x1B[2m2026-08-11T19:29:02.146314Z#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 1786476542.147 sable_ircd #x1B[2m2026-08-11T19:29:02.146586Z#x1B[0m #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(875098505041940486)), timestamp: 1786476542, change: ChannelInvite(ChannelInvite { invite: InviteId(UserId(Snowflake(875098492593246210)), ChannelId(Snowflake(875098496816910340))), source: User(HistoricUserId(UserId(Snowflake(875098489208442880)), 2)), user: HistoricUserId(UserId(Snowflake(875098492593246210)), 2) }), users_to_notify: [UserId(Snowflake(875098492593246210))] }) 1786476542.147 sable_ircd #x1B[2m2026-08-11T19:29:02.146630Z#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(875098505041940486)), timestamp: 1786476542, change: ChannelInvite(ChannelInvite { invite: InviteId(UserId(Snowflake(875098492593246210)), ChannelId(Snowflake(875098496816910340))), source: User(HistoricUserId(UserId(Snowflake(875098489208442880)), 2)), user: HistoricUserId(UserId(Snowflake(875098492593246210)), 2) }), users_to_notify: [UserId(Snowflake(875098492593246210))] } 1786476542.147 sable_ircd #x1B[2m2026-08-11T19:29:02.146700Z#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): :foo!username@localhost INVITE bar :#chan 1786476542.147 sable_ircd #x1B[2m2026-08-11T19:29:02.146747Z#x1B[0m #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(875098505041940486)), timestamp: 1786476542, change: EventComplete(EventComplete), users_to_notify: [] }) 1786476542.147 sable_ircd #x1B[2m2026-08-11T19:29:02.146769Z#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(875098505041940486)), timestamp: 1786476542, change: EventComplete(EventComplete), users_to_notify: [] } 1786476542.436 sable_history #x1B[2m2027-08-11T20:22:25.928255Z#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(1007383513617604669)), timestamp: 1818015745, clock: EventClock(1 => 1007369621558267926, 50 => 1007383261959364668, 99 => 1007369624859586564), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015745 }) }; event clock=EventClock(1 => 1007369621558267926, 50 => 1007383261959364668, 99 => 1007369624859586564) my clock=EventClock(1 => 1007369621558267926, 50 => 1007383261959364668, 99 => 1007369624859586564) 1786476542.436 sable_history #x1B[2m2027-08-11T20:22:25.929832Z#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 1786476542.479 sable_history #x1B[2m2027-08-11T20:11:05.417937Z#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(1007380659351789617)), timestamp: 1818015065, clock: EventClock(1 => 1007369401122426895, 50 => 1007380407693549616, 99 => 1007369391161356292), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1818015065 }) }; event clock=EventClock(1 => 1007369401122426895, 50 => 1007380407693549616, 99 => 1007369391161356292) my clock=EventClock(1 => 1007369401122426895, 50 => 1007380407693549616, 99 => 1007369391161356292) 1786476542.479 sable_history #x1B[2m2027-08-11T20:11:05.419744Z#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 1786476543.148 1: waiting… 1786476543.151 sable_ircd #x1B[2m2026-08-11T19:29:03.150605Z#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 :synchronize318.201404811 1786476543.151 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize318.201404811 1786476543.251 2 -> S: PING synchronize320.311789956 1786476543.251 S -> 2: :foo!username@localhost INVITE bar :#chan 1786476543.252 sable_ircd #x1B[2m2026-08-11T19:29:03.251807Z#x1B[0m #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 1786476543.252 sable_ircd #x1B[2m2026-08-11T19:29:03.251883Z#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 synchronize320.311789956" 1786476543.252 sable_ircd #x1B[2m2026-08-11T19:29:03.252031Z#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 :synchronize320.311789956 1786476543.252 S -> 2: :My.Little.Server PONG My.Little.Server :synchronize320.311789956 1786476543.257 1: disconnects from server. 1786476543.257 2: disconnects from server.