--------------------------------- Captured Out ---------------------------------
---- new test ----
1772747446.418 sable_ircd     uid=1001
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.421642Z#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(817514503926190080)), timestamp: 1772747446, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1772747446, name: ServerName("My.Little.Server"), ts: 1772747446, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock()
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.421757Z#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
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.421971Z#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(817514503926190080)), timestamp: 1772747446, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1772747446, name: ServerName("My.Little.Server"), ts: 1772747446, 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
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422161Z#x1B[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(817514503926190080)), timestamp: 1772747446, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] })
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422207Z#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(817514503926190080)), timestamp: 1772747446, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422209Z#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(817514503930384385)), timestamp: 1772747446, clock: EventClock(1 => 817514503926190080), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1772747446 }) }; event clock=EventClock(1 => 817514503926190080) my clock=EventClock(1 => 817514503926190080)
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422280Z#x1B[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
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422282Z#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
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422362Z#x1B[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(817514503926190080)), timestamp: 1772747446, change: EventComplete(EventComplete), users_to_notify: [] })
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422398Z#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(817514503926190080)), timestamp: 1772747446, change: EventComplete(EventComplete), users_to_notify: [] }
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422572Z#x1B[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(817514503930384385)), timestamp: 1772747446, change: EventComplete(EventComplete), users_to_notify: [] })
1772747446.423 sable_ircd     #x1B[2m2026-03-05T21:50:46.422611Z#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(817514503930384385)), timestamp: 1772747446, change: EventComplete(EventComplete), users_to_notify: [] }
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.514557Z#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 }
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.514733Z#x1B[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
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.514803Z#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
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.514873Z#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
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.515047Z#x1B[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
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.515111Z#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"
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.515250Z#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
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.515377Z#x1B[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))
1772747446.515 sable_ircd     #x1B[2m2026-03-05T21:50:46.515421Z#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)
1772747446.516 sable_ircd     #x1B[2m2026-03-05T21:50:46.515916Z#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
1772747446.516 sable_ircd     #x1B[2m2026-03-05T21:50:46.515995Z#x1B[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
1772747446.516 sable_ircd     #x1B[2m2026-03-05T21:50:46.516218Z#x1B[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
1772747446.535 chanop: connects to server.
1772747446.536 chanop -> S: NICK chanop
1772747446.536 chanop -> S: USER username * * :Realname
1772747446.536 sable_ircd     #x1B[2m2026-03-05T21:50:46.535900Z#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 }
1772747446.536 sable_ircd     #x1B[2m2026-03-05T21:50:46.536052Z#x1B[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
1772747446.536 sable_ircd     #x1B[2m2026-03-05T21:50:46.536095Z#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
1772747446.536 sable_ircd     #x1B[2m2026-03-05T21:50:46.536159Z#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
1772747446.536 sable_ircd     #x1B[2m2026-03-05T21:50:46.536298Z#x1B[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
1772747446.536 sable_ircd     #x1B[2m2026-03-05T21:50:46.536376Z#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 chanop"
1772747446.536 S -> chanop: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747446.537 sable_ircd     #x1B[2m2026-03-05T21:50:46.536587Z#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: 283, tv_nsec: 872802804 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(<uninit>), extra_user_params: OnceLock(<uninit>), nick: OnceLock(Nickname("chanop")), realname: OnceLock(<uninit>), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747446.537 sable_ircd     #x1B[2m2026-03-05T21:50:46.536660Z#x1B[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
1772747446.537 sable_ircd     #x1B[2m2026-03-05T21:50:46.536733Z#x1B[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
1772747446.537 sable_ircd     #x1B[2m2026-03-05T21:50:46.536784Z#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"
1772747446.537 sable_ircd     #x1B[2m2026-03-05T21:50:46.536882Z#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: 283, tv_nsec: 872802804 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("chanop")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.537480Z#x1B[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
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.537524Z#x1B[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"))
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.537556Z#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
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.537579Z#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: 283, tv_nsec: 872802804 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("chanop")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.537625Z#x1B[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))
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.537867Z#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(817514504412729346)), timestamp: 1772747446, clock: EventClock(1 => 817514503930384385), target: User(UserId(Snowflake(817514504412729344))), details: NewUser(NewUser { nickname: Nickname("chanop"), 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(817514504412729345)), NewUserConnection { user: UserId(Snowflake(817514504412729344)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747446 })) }) }; event clock=EventClock(1 => 817514503930384385) my clock=EventClock(1 => 817514503930384385)
1772747446.538 S -> chanop: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.537977Z#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
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.538241Z#x1B[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(817514504412729346)), timestamp: 1772747446, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817514504412729344)), 1) }), users_to_notify: [] })
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.538283Z#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(817514504412729346)), timestamp: 1772747446, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817514504412729344)), 1) }), users_to_notify: [] }
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.538373Z#x1B[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(817514504412729346)), timestamp: 1772747446, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817514504412729344)), 2), connection: UserConnectionId(Snowflake(817514504412729345)) }), users_to_notify: [] })
1772747446.538 sable_ircd     #x1B[2m2026-03-05T21:50:46.538411Z#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(817514504412729346)), timestamp: 1772747446, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817514504412729344)), 2), connection: UserConnectionId(Snowflake(817514504412729345)) }), users_to_notify: [] }
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538482Z#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 chanop :Welcome to the test Internet Relay Chat network, chanop
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538525Z#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 chanop :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538572Z#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 chanop :This server was created 2026-03-05 21:50:46.538557231 UTC
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538612Z#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 chanop My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538674Z#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 chanop 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
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538713Z#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 chanop :MOTD File is missing
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538758Z#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): :chanop!username@localhost MODE chanop +
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538794Z#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 chanop :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538845Z#x1B[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(817514504412729346)), timestamp: 1772747446, change: EventComplete(EventComplete), users_to_notify: [] })
1772747446.539 sable_ircd     #x1B[2m2026-03-05T21:50:46.538878Z#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(817514504412729346)), timestamp: 1772747446, change: EventComplete(EventComplete), users_to_notify: [] }
1772747446.539 S -> chanop: :My.Little.Server 001 chanop :Welcome to the test Internet Relay Chat network, chanop
1772747446.539 S -> chanop: :My.Little.Server 002 chanop :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747446.539 chanop -> S: PING foo
1772747446.540 sable_ircd     #x1B[2m2026-03-05T21:50:46.539497Z#x1B[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
1772747446.540 sable_ircd     #x1B[2m2026-03-05T21:50:46.539538Z#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"
1772747446.540 sable_ircd     #x1B[2m2026-03-05T21:50:46.539605Z#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
1772747446.740 chanop -> S: PING synchronize284.076140299
1772747446.740 S -> chanop: :My.Little.Server 003 chanop :This server was created 2026-03-05 21:50:46.538557231 UTC
1772747446.740 S -> chanop: :My.Little.Server 004 chanop My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747446.740 S -> chanop: :My.Little.Server 005 chanop 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
1772747446.740 S -> chanop: :My.Little.Server 422 chanop :MOTD File is missing
1772747446.740 S -> chanop: :chanop!username@localhost MODE chanop +
1772747446.740 S -> chanop: :My.Little.Server NOTICE chanop :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747446.740 S -> chanop: :My.Little.Server PONG My.Little.Server :foo
1772747446.740 sable_ircd     #x1B[2m2026-03-05T21:50:46.739949Z#x1B[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
1772747446.740 sable_ircd     #x1B[2m2026-03-05T21:50:46.740002Z#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 synchronize284.076140299"
1772747446.740 sable_ircd     #x1B[2m2026-03-05T21:50:46.740122Z#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 :synchronize284.076140299
1772747446.740 S -> chanop: :My.Little.Server PONG My.Little.Server :synchronize284.076140299
1772747447.341 chanop -> S: JOIN #chan
1772747447.342 sable_ircd     #x1B[2m2026-03-05T21:50:47.341696Z#x1B[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
1772747447.342 sable_ircd     #x1B[2m2026-03-05T21:50:47.341751Z#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"
1772747447.342 sable_ircd     #x1B[2m2026-03-05T21:50:47.342038Z#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(817514507789144067)), timestamp: 1772747447, clock: EventClock(1 => 817514504412729346), target: Channel(ChannelId(Snowflake(817514507784949762))), details: NewChannel(NewChannel { name: ChannelName("#chan"), mode: ChannelMode { modes: ChannelModeSet(0), key: None } }) }; event clock=EventClock(1 => 817514504412729346) my clock=EventClock(1 => 817514504412729346)
1772747447.342 sable_ircd     #x1B[2m2026-03-05T21:50:47.342235Z#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
1772747447.342 sable_ircd     #x1B[2m2026-03-05T21:50:47.342307Z#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(817514507789144068)), timestamp: 1772747447, clock: EventClock(1 => 817514507789144067), target: Membership(MembershipId(UserId(Snowflake(817514504412729344)), ChannelId(Snowflake(817514507784949762)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(817514507784949762)), user: UserId(Snowflake(817514504412729344)), permissions: MembershipFlagSet(1) }) }; event clock=EventClock(1 => 817514507789144067) my clock=EventClock(1 => 817514507789144067)
1772747447.342 sable_ircd     #x1B[2m2026-03-05T21:50:47.342450Z#x1B[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
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.342528Z#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
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.342743Z#x1B[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(817514507789144067)), timestamp: 1772747447, change: EventComplete(EventComplete), users_to_notify: [] })
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.342787Z#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(817514507789144067)), timestamp: 1772747447, change: EventComplete(EventComplete), users_to_notify: [] }
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.342846Z#x1B[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(817514507789144068)), timestamp: 1772747447, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(817514504412729344)), ChannelId(Snowflake(817514507784949762))), user: HistoricUserId(UserId(Snowflake(817514504412729344)), 2) }), users_to_notify: [UserId(Snowflake(817514504412729344))] })
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.342889Z#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(817514507789144068)), timestamp: 1772747447, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(817514504412729344)), ChannelId(Snowflake(817514507784949762))), user: HistoricUserId(UserId(Snowflake(817514504412729344)), 2) }), users_to_notify: [UserId(Snowflake(817514504412729344))] }
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.342969Z#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): :chanop!username@localhost JOIN #chan
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.343026Z#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): :chanop!username@localhost MODE #chan +o chanop
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.343084Z#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 chanop = #chan :@chanop
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.343121Z#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 chanop #chan :End of names list
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.343165Z#x1B[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(817514507789144068)), timestamp: 1772747447, change: EventComplete(EventComplete), users_to_notify: [] })
1772747447.343 sable_ircd     #x1B[2m2026-03-05T21:50:47.343198Z#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(817514507789144068)), timestamp: 1772747447, change: EventComplete(EventComplete), users_to_notify: [] }
1772747447.441 chanop -> S: PING synchronize284.778029738
1772747447.441 S -> chanop: :chanop!username@localhost JOIN #chan
1772747447.441 S -> chanop: :chanop!username@localhost MODE #chan +o chanop
1772747447.441 S -> chanop: :My.Little.Server 353 chanop = #chan :@chanop
1772747447.441 S -> chanop: :My.Little.Server 366 chanop #chan :End of names list
1772747447.442 sable_ircd     #x1B[2m2026-03-05T21:50:47.441759Z#x1B[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
1772747447.442 sable_ircd     #x1B[2m2026-03-05T21:50:47.441825Z#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 synchronize284.778029738"
1772747448.196 sable_ircd     #x1B[2m2026-03-05T21:50:48.195650Z#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 :synchronize284.778029738
1772747448.196 S -> chanop: :My.Little.Server PONG My.Little.Server :synchronize284.778029738
1772747448.296 chanop -> S: PING synchronize285.632880149
1772747448.297 sable_ircd     #x1B[2m2026-03-05T21:50:48.296694Z#x1B[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
1772747448.297 sable_ircd     #x1B[2m2026-03-05T21:50:48.296771Z#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 synchronize285.632880149"
1772747449.051 sable_ircd     #x1B[2m2026-03-05T21:50:49.050637Z#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 :synchronize285.632880149
1772747449.051 S -> chanop: :My.Little.Server PONG My.Little.Server :synchronize285.632880149
1772747449.051 chanop -> S: MODE #chan +i
1772747449.051 sable_ircd     #x1B[2m2026-03-05T21:50:49.051449Z#x1B[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
1772747449.052 sable_ircd     #x1B[2m2026-03-05T21:50:49.051510Z#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"MODE #chan +i"
1772747449.151 chanop -> S: PING synchronize286.487933034
1772747449.152 sable_ircd     #x1B[2m2026-03-05T21:50:49.151808Z#x1B[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
1772747449.152 sable_ircd     #x1B[2m2026-03-05T21:50:49.151895Z#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 synchronize286.487933034"
1772747450.152 chanop: waiting…
1772747450.157 sable_ircd     #x1B[2m2026-03-05T21:50:50.156506Z#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(817514519591915525)), timestamp: 1772747450, clock: EventClock(1 => 817514507789144068), target: Channel(ChannelId(Snowflake(817514507784949762))), details: ChannelModeChange(ChannelModeChange { changed_by: User(UserId(Snowflake(817514504412729344))), added: ChannelModeSet(8), removed: ChannelModeSet(0), key_change: NoChange }) }; event clock=EventClock(1 => 817514507789144068) my clock=EventClock(1 => 817514507789144068)
1772747450.157 sable_ircd     #x1B[2m2026-03-05T21:50:50.156673Z#x1B[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
1772747450.157 sable_ircd     #x1B[2m2026-03-05T21:50:50.156782Z#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
1772747450.157 sable_ircd     #x1B[2m2026-03-05T21:50:50.157044Z#x1B[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(817514519591915525)), timestamp: 1772747450, change: ChannelModeChange(ChannelModeChange { channel: ChannelId(Snowflake(817514507784949762)), added: ChannelModeSet(8), removed: ChannelModeSet(0), key_change: NoChange, changed_by: User(HistoricUserId(UserId(Snowflake(817514504412729344)), 2)) }), users_to_notify: [UserId(Snowflake(817514504412729344))] })
1772747450.158 sable_ircd     #x1B[2m2026-03-05T21:50:50.157095Z#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(817514519591915525)), timestamp: 1772747450, change: ChannelModeChange(ChannelModeChange { channel: ChannelId(Snowflake(817514507784949762)), added: ChannelModeSet(8), removed: ChannelModeSet(0), key_change: NoChange, changed_by: User(HistoricUserId(UserId(Snowflake(817514504412729344)), 2)) }), users_to_notify: [UserId(Snowflake(817514504412729344))] }
1772747450.158 sable_ircd     #x1B[2m2026-03-05T21:50:50.157177Z#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): :chanop!username@localhost MODE #chan +i
1772747450.158 sable_ircd     #x1B[2m2026-03-05T21:50:50.157260Z#x1B[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(817514519591915525)), timestamp: 1772747450, change: EventComplete(EventComplete), users_to_notify: [] })
1772747450.158 sable_ircd     #x1B[2m2026-03-05T21:50:50.157295Z#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(817514519591915525)), timestamp: 1772747450, change: EventComplete(EventComplete), users_to_notify: [] }
1772747450.159 S -> chanop: :chanop!username@localhost MODE #chan +i
1772747451.160 chanop: waiting…
1772747451.161 sable_ircd     #x1B[2m2026-03-05T21:50:51.161016Z#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 :synchronize286.487933034
1772747451.161 S -> chanop: :My.Little.Server PONG My.Little.Server :synchronize286.487933034
1772747451.162 chanop -> S: MODE #chan +I bar!*@*
1772747451.162 sable_ircd     #x1B[2m2026-03-05T21:50:51.161776Z#x1B[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
1772747451.162 sable_ircd     #x1B[2m2026-03-05T21:50:51.161833Z#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"MODE #chan +I bar!*@*"
1772747451.262 chanop -> S: PING synchronize288.598328291
1772747451.262 sable_ircd     #x1B[2m2026-03-05T21:50:51.262250Z#x1B[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
1772747451.262 sable_ircd     #x1B[2m2026-03-05T21:50:51.262372Z#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 synchronize288.598328291"
1772747452.016 sable_ircd     #x1B[2m2026-03-05T21:50:52.015544Z#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(817514527389126662)), timestamp: 1772747452, clock: EventClock(1 => 817514519591915525), target: ListModeEntry(ListModeEntryId(Snowflake(817514527389126659))), details: NewListModeEntry(NewListModeEntry { list: ListModeId(ChannelId(Snowflake(817514507784949762)), Invex), pattern: Pattern("bar!*@*"), setter: UserId(Snowflake(817514504412729344)) }) }; event clock=EventClock(1 => 817514519591915525) my clock=EventClock(1 => 817514519591915525)
1772747452.016 sable_ircd     #x1B[2m2026-03-05T21:50:52.015775Z#x1B[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
1772747452.016 sable_ircd     #x1B[2m2026-03-05T21:50:52.015780Z#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
1772747452.016 sable_ircd     #x1B[2m2026-03-05T21:50:52.016127Z#x1B[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(817514527389126662)), timestamp: 1772747452, change: ListModeAdded(ListModeAdded { channel: ChannelId(Snowflake(817514507784949762)), list_type: Invex, pattern: Pattern("bar!*@*"), set_by: User(HistoricUserId(UserId(Snowflake(817514504412729344)), 2)) }), users_to_notify: [UserId(Snowflake(817514504412729344))] })
1772747452.016 sable_ircd     #x1B[2m2026-03-05T21:50:52.016176Z#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(817514527389126662)), timestamp: 1772747452, change: ListModeAdded(ListModeAdded { channel: ChannelId(Snowflake(817514507784949762)), list_type: Invex, pattern: Pattern("bar!*@*"), set_by: User(HistoricUserId(UserId(Snowflake(817514504412729344)), 2)) }), users_to_notify: [UserId(Snowflake(817514504412729344))] }
1772747452.016 sable_ircd     #x1B[2m2026-03-05T21:50:52.016237Z#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): :chanop!username@localhost MODE #chan +I bar!*@*
1772747452.016 sable_ircd     #x1B[2m2026-03-05T21:50:52.016297Z#x1B[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(817514527389126662)), timestamp: 1772747452, change: EventComplete(EventComplete), users_to_notify: [] })
1772747452.016 sable_ircd     #x1B[2m2026-03-05T21:50:52.016319Z#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(817514527389126662)), timestamp: 1772747452, change: EventComplete(EventComplete), users_to_notify: [] }
1772747452.017 S -> chanop: :chanop!username@localhost MODE #chan +I bar!*@*
1772747453.017 chanop: waiting…
1772747453.020 sable_ircd     #x1B[2m2026-03-05T21:50:53.020137Z#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 :synchronize288.598328291
1772747453.021 S -> chanop: :My.Little.Server PONG My.Little.Server :synchronize288.598328291
1772747453.021 bar: connects to server.
1772747453.021 bar -> S: NICK Bar
1772747453.021 bar -> S: USER username * * :Realname
1772747453.021 sable_ircd     #x1B[2m2026-03-05T21:50:53.021103Z#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 }
1772747453.021 sable_ircd     #x1B[2m2026-03-05T21:50:53.021235Z#x1B[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
1772747453.021 sable_ircd     #x1B[2m2026-03-05T21:50:53.021292Z#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
1772747453.021 sable_ircd     #x1B[2m2026-03-05T21:50:53.021387Z#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
1772747453.022 sable_ircd     #x1B[2m2026-03-05T21:50:53.021554Z#x1B[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
1772747453.022 sable_ircd     #x1B[2m2026-03-05T21:50:53.021602Z#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"
1772747453.022 S -> bar: :My.Little.Server NOTICE * :*** Looking up your hostname
1772747453.022 sable_ircd     #x1B[2m2026-03-05T21:50:53.021704Z#x1B[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
1772747453.022 sable_ircd     #x1B[2m2026-03-05T21:50:53.021753Z#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"
1772747453.022 sable_ircd     #x1B[2m2026-03-05T21:50:53.021844Z#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: 290, tv_nsec: 358026336 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(<uninit>), realname: OnceLock(Realname("Realname")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747453.022 sable_ircd     #x1B[2m2026-03-05T21:50:53.021976Z#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: 290, tv_nsec: 358026336 }, attach_user_id: OnceLock(<uninit>), user: OnceLock(Username("username")), extra_user_params: OnceLock(("*", "*")), nick: OnceLock(Nickname("Bar")), realname: OnceLock(Realname("Realname")), hostname: OnceLock(<uninit>), sasl_session: Mutex { data: None }, sasl_account: OnceLock(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747453.022 sable_ircd     #x1B[2m2026-03-05T21:50:53.022037Z#x1B[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
1772747453.023 sable_ircd     #x1B[2m2026-03-05T21:50:53.022880Z#x1B[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
1772747453.023 sable_ircd     #x1B[2m2026-03-05T21:50:53.022980Z#x1B[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"))
1772747453.023 sable_ircd     #x1B[2m2026-03-05T21:50:53.023047Z#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
1772747453.023 sable_ircd     #x1B[2m2026-03-05T21:50:53.023102Z#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: 290, tv_nsec: 358026336 }, attach_user_id: OnceLock(<uninit>), 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(<uninit>), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse
1772747453.023 sable_ircd     #x1B[2m2026-03-05T21:50:53.023185Z#x1B[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))
1772747453.024 sable_ircd     #x1B[2m2026-03-05T21:50:53.023678Z#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(817514531616985095)), timestamp: 1772747453, clock: EventClock(1 => 817514527389126662), target: User(UserId(Snowflake(817514531616985092))), 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(817514531616985093)), NewUserConnection { user: UserId(Snowflake(817514531616985092)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1772747453 })) }) }; event clock=EventClock(1 => 817514527389126662) my clock=EventClock(1 => 817514527389126662)
1772747453.024 S -> bar: :My.Little.Server NOTICE * :*** Found your hostname: localhost
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.025476Z#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
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.025780Z#x1B[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(817514531616985095)), timestamp: 1772747453, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817514531616985092)), 1) }), users_to_notify: [] })
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.025816Z#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(817514531616985095)), timestamp: 1772747453, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(817514531616985092)), 1) }), users_to_notify: [] }
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.025874Z#x1B[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(817514531616985095)), timestamp: 1772747453, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817514531616985092)), 2), connection: UserConnectionId(Snowflake(817514531616985093)) }), users_to_notify: [] })
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.025908Z#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(817514531616985095)), timestamp: 1772747453, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(817514531616985092)), 2), connection: UserConnectionId(Snowflake(817514531616985093)) }), users_to_notify: [] }
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.025969Z#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
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026015Z#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
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026047Z#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-03-05 21:50:53.026035842 UTC
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026071Z#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
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026103Z#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
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026127Z#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
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026152Z#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 +
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026172Z#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.
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026214Z#x1B[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(817514531616985095)), timestamp: 1772747453, change: EventComplete(EventComplete), users_to_notify: [] })
1772747453.027 sable_ircd     #x1B[2m2026-03-05T21:50:53.026243Z#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(817514531616985095)), timestamp: 1772747453, change: EventComplete(EventComplete), users_to_notify: [] }
1772747453.027 S -> bar: :My.Little.Server 001 Bar :Welcome to the test Internet Relay Chat network, Bar
1772747453.027 S -> bar: :My.Little.Server 002 Bar :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432
1772747453.027 S -> bar: :My.Little.Server 003 Bar :This server was created 2026-03-05 21:50:53.026035842 UTC
1772747453.027 S -> bar: :My.Little.Server 004 Bar My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov
1772747453.027 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
1772747453.027 S -> bar: :My.Little.Server 422 Bar :MOTD File is missing
1772747453.027 S -> bar: :Bar!username@localhost MODE Bar +
1772747453.027 S -> bar: :My.Little.Server NOTICE Bar :The network is currently running in debug mode. Do not send any sensitive information such as passwords.
1772747453.028 sable_ircd     #x1B[2m2026-03-05T21:50:53.027655Z#x1B[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
1772747453.028 sable_ircd     #x1B[2m2026-03-05T21:50:53.027810Z#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"
1772747453.028 bar -> S: PING foo
1772747453.028 sable_ircd     #x1B[2m2026-03-05T21:50:53.027979Z#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
1772747453.829 bar -> S: PING synchronize291.165493103
1772747453.829 S -> bar: :My.Little.Server PONG My.Little.Server :foo
1772747453.829 sable_ircd     #x1B[2m2026-03-05T21:50:53.829322Z#x1B[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
1772747453.829 sable_ircd     #x1B[2m2026-03-05T21:50:53.829400Z#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 synchronize291.165493103"
1772747453.830 sable_ircd     #x1B[2m2026-03-05T21:50:53.829519Z#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 :synchronize291.165493103
1772747453.830 S -> bar: :My.Little.Server PONG My.Little.Server :synchronize291.165493103
1772747453.830 bar -> S: JOIN #chan
1772747453.830 sable_ircd     #x1B[2m2026-03-05T21:50:53.830201Z#x1B[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
1772747453.830 sable_ircd     #x1B[2m2026-03-05T21:50:53.830256Z#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 #chan"
1772747453.930 bar -> S: PING synchronize291.266898069
1772747453.931 sable_ircd     #x1B[2m2026-03-05T21:50:53.930858Z#x1B[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
1772747453.931 sable_ircd     #x1B[2m2026-03-05T21:50:53.930931Z#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 synchronize291.266898069"
1772747454.183 sable_ircd     #x1B[2m2026-03-05T21:50:54.182758Z#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(817514536478183432)), timestamp: 1772747454, clock: EventClock(1 => 817514531616985095), target: Membership(MembershipId(UserId(Snowflake(817514531616985092)), ChannelId(Snowflake(817514507784949762)))), details: ChannelJoin(ChannelJoin { channel: ChannelId(Snowflake(817514507784949762)), user: UserId(Snowflake(817514531616985092)), permissions: MembershipFlagSet(0) }) }; event clock=EventClock(1 => 817514531616985095) my clock=EventClock(1 => 817514531616985095)
1772747454.183 sable_ircd     #x1B[2m2026-03-05T21:50:54.182925Z#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
1772747454.183 sable_ircd     #x1B[2m2026-03-05T21:50:54.182924Z#x1B[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
1772747454.183 sable_ircd     #x1B[2m2026-03-05T21:50:54.183150Z#x1B[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(817514536478183432)), timestamp: 1772747454, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(817514531616985092)), ChannelId(Snowflake(817514507784949762))), user: HistoricUserId(UserId(Snowflake(817514531616985092)), 2) }), users_to_notify: [UserId(Snowflake(817514531616985092)), UserId(Snowflake(817514504412729344))] })
1772747454.183 sable_ircd     #x1B[2m2026-03-05T21:50:54.183193Z#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(817514536478183432)), timestamp: 1772747454, change: ChannelJoin(ChannelJoin { membership: MembershipId(UserId(Snowflake(817514531616985092)), ChannelId(Snowflake(817514507784949762))), user: HistoricUserId(UserId(Snowflake(817514531616985092)), 2) }), users_to_notify: [UserId(Snowflake(817514531616985092)), UserId(Snowflake(817514504412729344))] }
1772747454.183 sable_ircd     #x1B[2m2026-03-05T21:50:54.183272Z#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 JOIN #chan
1772747454.183 sable_ircd     #x1B[2m2026-03-05T21:50:54.183371Z#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 Bar = #chan :Bar @chanop
1772747454.183 sable_ircd     #x1B[2m2026-03-05T21:50:54.183412Z#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 Bar #chan :End of names list
1772747454.184 sable_ircd     #x1B[2m2026-03-05T21:50:54.183475Z#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 #chan
1772747454.184 sable_ircd     #x1B[2m2026-03-05T21:50:54.183552Z#x1B[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(817514536478183432)), timestamp: 1772747454, change: EventComplete(EventComplete), users_to_notify: [] })
1772747454.184 sable_ircd     #x1B[2m2026-03-05T21:50:54.183587Z#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(817514536478183432)), timestamp: 1772747454, change: EventComplete(EventComplete), users_to_notify: [] }
1772747454.184 S -> bar: :Bar!username@localhost JOIN #chan
1772747454.224 S -> bar: :My.Little.Server 353 Bar = #chan :Bar @chanop
1772747454.224 S -> bar: :My.Little.Server 366 Bar #chan :End of names list
1772747455.188 sable_ircd     #x1B[2m2026-03-05T21:50:55.187981Z#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 :synchronize291.266898069
1772747455.188 S -> bar: :My.Little.Server PONG My.Little.Server :synchronize291.266898069
1772747455.188 bar -> S: PART #chan
1772747455.189 sable_ircd     #x1B[2m2026-03-05T21:50:55.188747Z#x1B[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
1772747455.189 sable_ircd     #x1B[2m2026-03-05T21:50:55.188793Z#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"PART #chan"
1772747455.289 bar -> S: PING synchronize292.625225484
1772747455.289 sable_ircd     #x1B[2m2026-03-05T21:50:55.289015Z#x1B[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
1772747455.289 sable_ircd     #x1B[2m2026-03-05T21:50:55.289069Z#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 synchronize292.625225484"
1772747456.043 sable_ircd     #x1B[2m2026-03-05T21:50:56.042971Z#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(817514544279588873)), timestamp: 1772747456, clock: EventClock(1 => 817514536478183432), target: Membership(MembershipId(UserId(Snowflake(817514531616985092)), ChannelId(Snowflake(817514507784949762)))), details: ChannelPart(ChannelPart { message: "Bar" }) }; event clock=EventClock(1 => 817514536478183432) my clock=EventClock(1 => 817514536478183432)
1772747456.043 sable_ircd     #x1B[2m2026-03-05T21:50:56.043151Z#x1B[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
1772747456.043 sable_ircd     #x1B[2m2026-03-05T21:50:56.043151Z#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
1772747456.043 sable_ircd     #x1B[2m2026-03-05T21:50:56.043447Z#x1B[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(817514544279588873)), timestamp: 1772747456, change: ChannelPart(ChannelPart { membership: Membership { id: MembershipId(UserId(Snowflake(817514531616985092)), ChannelId(Snowflake(817514507784949762))), channel: ChannelId(Snowflake(817514507784949762)), user: UserId(Snowflake(817514531616985092)), permissions: MembershipFlagSet(0) }, user: HistoricUserId(UserId(Snowflake(817514531616985092)), 2), message: "Bar" }), users_to_notify: [UserId(Snowflake(817514504412729344)), UserId(Snowflake(817514531616985092))] })
1772747456.044 sable_ircd     #x1B[2m2026-03-05T21:50:56.043489Z#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(817514544279588873)), timestamp: 1772747456, change: ChannelPart(ChannelPart { membership: Membership { id: MembershipId(UserId(Snowflake(817514531616985092)), ChannelId(Snowflake(817514507784949762))), channel: ChannelId(Snowflake(817514507784949762)), user: UserId(Snowflake(817514531616985092)), permissions: MembershipFlagSet(0) }, user: HistoricUserId(UserId(Snowflake(817514531616985092)), 2), message: "Bar" }), users_to_notify: [UserId(Snowflake(817514504412729344)), UserId(Snowflake(817514531616985092))] }
1772747456.044 sable_ircd     #x1B[2m2026-03-05T21:50:56.043540Z#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 PART #chan :Bar
1772747456.044 sable_ircd     #x1B[2m2026-03-05T21:50:56.043577Z#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 PART #chan :Bar
1772747456.044 sable_ircd     #x1B[2m2026-03-05T21:50:56.043630Z#x1B[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(817514544279588873)), timestamp: 1772747456, change: EventComplete(EventComplete), users_to_notify: [] })
1772747456.044 sable_ircd     #x1B[2m2026-03-05T21:50:56.043654Z#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(817514544279588873)), timestamp: 1772747456, change: EventComplete(EventComplete), users_to_notify: [] }
1772747456.044 S -> bar: :Bar!username@localhost PART #chan :Bar
1772747457.045 bar: waiting…
1772747457.048 sable_ircd     #x1B[2m2026-03-05T21:50:57.048150Z#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 :synchronize292.625225484
1772747457.049 S -> bar: :My.Little.Server PONG My.Little.Server :synchronize292.625225484
1772747457.149 chanop -> S: PING synchronize294.48539621
1772747457.149 S -> chanop: :Bar!username@localhost JOIN #chan
1772747457.149 S -> chanop: :Bar!username@localhost PART #chan :Bar
1772747457.149 sable_ircd     #x1B[2m2026-03-05T21:50:57.149176Z#x1B[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
1772747457.149 sable_ircd     #x1B[2m2026-03-05T21:50:57.149247Z#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 synchronize294.48539621"
1772747457.149 sable_ircd     #x1B[2m2026-03-05T21:50:57.149441Z#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 :synchronize294.48539621
1772747457.150 S -> chanop: :My.Little.Server PONG My.Little.Server :synchronize294.48539621
1772747457.150 chanop -> S: MODE #chan -I bar!*@*
1772747457.150 sable_ircd     #x1B[2m2026-03-05T21:50:57.150029Z#x1B[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
1772747457.150 sable_ircd     #x1B[2m2026-03-05T21:50:57.150071Z#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"MODE #chan -I bar!*@*"
1772747457.150 sable_ircd     #x1B[2m2026-03-05T21:50:57.150292Z#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(817514548926877706)), timestamp: 1772747457, clock: EventClock(1 => 817514544279588873), target: ListModeEntry(ListModeEntryId(Snowflake(817514527389126659))), details: DelListModeEntry(DelListModeEntry { removed_by: UserId(Snowflake(817514504412729344)) }) }; event clock=EventClock(1 => 817514544279588873) my clock=EventClock(1 => 817514544279588873)
1772747457.150 sable_ircd     #x1B[2m2026-03-05T21:50:57.150442Z#x1B[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
1772747457.151 sable_ircd     #x1B[2m2026-03-05T21:50:57.150462Z#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
1772747457.151 sable_ircd     #x1B[2m2026-03-05T21:50:57.150732Z#x1B[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(817514548926877706)), timestamp: 1772747457, change: ListModeRemoved(ListModeRemoved { channel: ChannelId(Snowflake(817514507784949762)), list_type: Invex, pattern: Pattern("bar!*@*"), removed_by: User(HistoricUserId(UserId(Snowflake(817514504412729344)), 2)) }), users_to_notify: [UserId(Snowflake(817514504412729344))] })
1772747457.151 sable_ircd     #x1B[2m2026-03-05T21:50:57.150772Z#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(817514548926877706)), timestamp: 1772747457, change: ListModeRemoved(ListModeRemoved { channel: ChannelId(Snowflake(817514507784949762)), list_type: Invex, pattern: Pattern("bar!*@*"), removed_by: User(HistoricUserId(UserId(Snowflake(817514504412729344)), 2)) }), users_to_notify: [UserId(Snowflake(817514504412729344))] }
1772747457.151 sable_ircd     #x1B[2m2026-03-05T21:50:57.150824Z#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): :chanop!username@localhost MODE #chan -I bar!*@*
1772747457.151 sable_ircd     #x1B[2m2026-03-05T21:50:57.150879Z#x1B[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(817514548926877706)), timestamp: 1772747457, change: EventComplete(EventComplete), users_to_notify: [] })
1772747457.151 sable_ircd     #x1B[2m2026-03-05T21:50:57.150904Z#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(817514548926877706)), timestamp: 1772747457, change: EventComplete(EventComplete), users_to_notify: [] }
1772747457.250 chanop -> S: PING synchronize294.586585559
1772747457.250 S -> chanop: :chanop!username@localhost MODE #chan -I bar!*@*
1772747457.251 sable_ircd     #x1B[2m2026-03-05T21:50:57.250455Z#x1B[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
1772747457.251 sable_ircd     #x1B[2m2026-03-05T21:50:57.250546Z#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 synchronize294.586585559"
1772747457.251 sable_ircd     #x1B[2m2026-03-05T21:50:57.250716Z#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 :synchronize294.586585559
1772747457.251 S -> chanop: :My.Little.Server PONG My.Little.Server :synchronize294.586585559
1772747457.251 bar -> S: JOIN #chan
1772747457.251 sable_ircd     #x1B[2m2026-03-05T21:50:57.251329Z#x1B[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
1772747457.251 sable_ircd     #x1B[2m2026-03-05T21:50:57.251398Z#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 #chan"
1772747457.351 bar -> S: PING synchronize294.68787985
1772747457.352 sable_ircd     #x1B[2m2026-03-05T21:50:57.351708Z#x1B[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
1772747457.352 sable_ircd     #x1B[2m2026-03-05T21:50:57.351771Z#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 synchronize294.68787985"
1772747458.106 sable_ircd     #x1B[2m2026-03-05T21:50:58.105590Z#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 473 Bar #chan :Cannot join channel (+i) - you must be invited
1772747458.106 sable_ircd     #x1B[2m2026-03-05T21:50:58.105658Z#x1B[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
1772747458.106 S -> bar: :My.Little.Server 473 Bar #chan :Cannot join channel (+i) - you must be invited
1772747459.106 bar: waiting…
1772747459.110 sable_ircd     #x1B[2m2026-03-05T21:50:59.110116Z#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 :synchronize294.68787985
1772747459.110 S -> bar: :My.Little.Server PONG My.Little.Server :synchronize294.68787985
1772747459.114 chanop: disconnects from server.
1772747459.114 bar: disconnects from server.