2025-09-11 21:21:07,809 Generated random file: bnxibxxdry of size: 1024 2025-09-11 21:21:07,810 Requests: https://server4:443/bnxibxxdry 2025-09-11 21:21:07,869 2025-09-11 21:21:07,869 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_5vj88az8/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_67p55sje/ DOWNLOADS=/tmp/download_d_7sig62/ SERVER_LOGS=/tmp/logs_server_bknw4ca4 CLIENT_LOGS=/tmp/logs_client_bamfnkqh SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/alibaba/xquic/xquic-interop:latest REQUESTS="https://server4:443/bnxibxxdry" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-11 21:21:34,273 Container sim Recreate Container sim Recreated Container server Recreate Container client Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 client | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + '[' -n handshake ']' client | + '[' -n /logs/qlog/ ']' client | + case "$ROLE" in client | + /wait-for-it.sh sim:57832 -s -t 30 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | ./demo_server -l d -L /logs/server.log -p 443 -D /www -k /logs/keys.log -i -M server | option log level :d server | option log directory :/logs/server.log server | option port :443 server | option read dir :/www server | option key output file: /logs/keys.log server | set interop mode server | option multipath enabled server | /logs/keys.log 4 server | create ipv4 socket fd: 9 server | create ipv6 socket fd: 10 sim | server:443 is available after 501.670807ms sim | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads) client | + '[' https://server4:443/bnxibxxdry ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test handshake --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/bnxibxxdry client | ' client | ++ tee -i -a /logs/client.log client | 0.000 DEBUG Logging initialized client | 0.001 DEBUG Default socket send buffer size is Ok(212992) client | 0.001 DEBUG Default socket receive buffer size is 1048576, not changing client | 0.001 INFO hq-interop Client connecting: 0.0.0.0:38683 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=4c12a5ec49b51021 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=32 client | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.001 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 0.001 DEBUG [unv-path 0.0.0.0:38683->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443] Path validated Instant { tv_sec: 441, tv_nsec: 877445324 } client | 0.001 DEBUG [Client 4c12a5ec49b51021] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x55b8da9da500] state -> InProgress client | 0.002 DEBUG [Client 4c12a5ec49b51021] State change from Init -> WaitInitial client | 0.002 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1213 client | 0.002 DEBUG [Client 4c12a5ec49b51021] pn=32 type=Initial pri-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.002 DEBUG packet_sent this=0x55b8da9edaf0, pn=32, ps=1252 client | 0.002 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=327 client | 0.002 DEBUG [Client 4c12a5ec49b51021] pn=33 type=Initial pri-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 367 client | TX -> Crypto { offset: 1213, len: 327 } client | 0.002 DEBUG [Client 4c12a5ec49b51021] pad Initial from 367 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55b8da9edaf0, pn=33, ps=1252 client | 0.002 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 4c12a5ec49b51021] delay duration 3.865662ms client | 0.002 DEBUG Setting timeout of 3.865662ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 4c12a5ec49b51021] delay duration 3.834794ms client | 0.002 DEBUG Setting timeout of 3.834794ms client | 0.007 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.007 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG [Client 4c12a5ec49b51021] delay duration 294.287481ms client | 0.007 DEBUG Setting timeout of 294.287481ms client | 0.302 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.302 DEBUG [Client 4c12a5ec49b51021] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.302 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.302 DEBUG [Client 4c12a5ec49b51021] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 327 }) client | 0.302 INFO Lost crypto frame space=in offset=1213 length=327 client | 0.302 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.302 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.302 DEBUG CRYPTO for in offset=0, len=1213 client | 0.302 DEBUG [Client 4c12a5ec49b51021] pn=34 type=Initial pri-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.302 DEBUG packet_sent this=0x55b8da9edaf0, pn=34, ps=1252 client | 0.302 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.302 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.302 DEBUG CRYPTO for in offset=1213, len=327 client | 0.302 DEBUG [Client 4c12a5ec49b51021] pn=35 type=Initial pri-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 367 client | TX -> Crypto { offset: 1213, len: 327 } client | 0.302 DEBUG [Client 4c12a5ec49b51021] pad Initial from 367 to PLPMTU 1252 client | 0.302 DEBUG packet_sent this=0x55b8da9edaf0, pn=35, ps=1252 client | 0.302 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.302 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.302 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.303 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.303 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.303 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.303 DEBUG [Client 4c12a5ec49b51021] delay duration 599.786343ms client | 0.303 DEBUG Setting timeout of 599.786343ms client | 0.904 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.904 DEBUG [Client 4c12a5ec49b51021] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.904 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.904 DEBUG [Client 4c12a5ec49b51021] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 327 }) client | 0.904 INFO Lost crypto frame space=in offset=1213 length=327 client | 0.904 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.904 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.904 DEBUG CRYPTO for in offset=0, len=1213 client | 0.904 DEBUG [Client 4c12a5ec49b51021] pn=36 type=Initial pri-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.904 DEBUG packet_sent this=0x55b8da9edaf0, pn=36, ps=1252 client | 0.904 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.904 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.904 DEBUG CRYPTO for in offset=1213, len=327 client | 0.904 DEBUG [Client 4c12a5ec49b51021] pn=37 type=Initial pri-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 367 client | TX -> Crypto { offset: 1213, len: 327 } client | 0.904 DEBUG [Client 4c12a5ec49b51021] pad Initial from 367 to PLPMTU 1252 client | 0.904 DEBUG packet_sent this=0x55b8da9edaf0, pn=37, ps=1252 client | 0.904 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.904 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.904 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.904 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.904 DEBUG Building Initial dcid Some(CID [8]: 4c12a5ec49b51021) scid Some(CID [0]: ) client | 0.904 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.904 DEBUG [Client 4c12a5ec49b51021] delay duration 1.19979041s client | 0.904 DEBUG Setting timeout of 1.19979041s client | 1.509 DEBUG [Client 4c12a5ec49b51021] pn=0 type=Initial pri-path:4c12a5ec49b51021 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 48 client | -> RX Ack { largest_acknowledged: 32, ack_delay: 77, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 1.509 DEBUG [Client 4c12a5ec49b51021] Rx ACK space=in, ranges=[32..=32] client | 1.509 DEBUG [recovery::Loss] ACK for Initial - largest_acked=32 client | 1.509 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6260/13772 ssthresh 18446744073709551615] slow start += 1252 client | 1.509 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6260/13772 ssthresh 18446744073709551615] on_packets_acked this=0x55b8da9edaf0, limited=0, bytes_in_flight=6260, cwnd=13772, state=SlowStart, new_acked=1252 client | 1.509 DEBUG Acked crypto frame space=in offset=0 length=1213 client | 1.509 DEBUG [Client 4c12a5ec49b51021] Changing to use Server CID=00c5738fb9d6d94621c0a105 client | 1.509 DEBUG [Client 4c12a5ec49b51021] State change from WaitInitial -> WaitVersion client | 1.509 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.509 DEBUG Building Initial dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.509 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.509 DEBUG [Client 4c12a5ec49b51021] delay duration 3.918821571s client | 1.509 DEBUG Setting timeout of 3.918821571s client | 1.509 WARN Unhandled event StateChange(WaitVersion) client | 1.509 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.509 DEBUG Building Initial dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.509 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.509 DEBUG [Client 4c12a5ec49b51021] delay duration 3.918786265s client | 1.509 DEBUG Setting timeout of 3.918786265s client | 1.511 DEBUG [Client 4c12a5ec49b51021] pn=1 type=Initial pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 1200 client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Padding { len: 1064 } client | 1.511 DEBUG Write secret available for Handshake: SymKey [32]: 78a0f562081258671e192930ac08a91329f89f6b6bfa2d2a3a17367b9cc4ad66 client | 1.511 DEBUG Read secret available for Handshake: SymKey [32]: 9168baa4c0131582887403b09133f445d1f8db75929e09678b01e7c15d971550 client | 1.511 DEBUG [Agent 0x55b8da9da500] state -> InProgress client | 1.511 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 1.511 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 1.511 DEBUG [Crypto] Handshake keys installed client | 1.511 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 443, tv_nsec: 387559304 } client | 1.511 DEBUG [Client 4c12a5ec49b51021] State change from WaitVersion -> Handshaking client | 1.511 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.511 DEBUG Building Initial dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG [Client 4c12a5ec49b51021] pn=38 type=Initial pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 62, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 1.511 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG [Client 4c12a5ec49b51021] pad Initial from 44 to PLPMTU 1252 client | 1.511 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.511 DEBUG Building Initial dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.511 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.511 DEBUG Building Initial dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.511 DEBUG [Client 4c12a5ec49b51021] delay duration 3.916727126s client | 1.511 DEBUG Setting timeout of 3.916727126s client | 1.511 DEBUG [Client 4c12a5ec49b51021] pn=0 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 183 client | -> RX Crypto { offset: 0, len: 138 } client | 1.511 DEBUG [Agent 0x55b8da9da500] state -> InProgress client | 1.511 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 443, tv_nsec: 388163096 } client | 1.511 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.511 DEBUG Building Initial dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG [Client 4c12a5ec49b51021] pn=0 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 4, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 1.511 DEBUG [Client 4c12a5ec49b51021] Drop packet number space in client | 1.511 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 1.511 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.511 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.511 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.511 DEBUG [Client 4c12a5ec49b51021] delay duration 4.523704454s client | 1.511 DEBUG Setting timeout of 4.523704454s client | 1.511 DEBUG [Client 4c12a5ec49b51021] pn=1 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 517 client | -> RX Crypto { offset: 138, len: 471 } client | 1.512 DEBUG [Agent 0x55b8da9da500] state -> AuthenticationPending client | 1.512 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 443, tv_nsec: 388303598 } client | 1.512 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.512 DEBUG [Client 4c12a5ec49b51021] pn=1 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 16, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 1.512 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.512 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG [Client 4c12a5ec49b51021] delay duration 4.52349794s client | 1.512 DEBUG Setting timeout of 4.52349794s client | 1.512 DEBUG [Client 4c12a5ec49b51021] pn=2 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 125 client | -> RX Crypto { offset: 609, len: 79 } client | 1.512 DEBUG [Agent 0x55b8da9da500] state -> AuthenticationPending client | 1.512 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 443, tv_nsec: 388510442 } client | 1.512 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.512 DEBUG [Client 4c12a5ec49b51021] pn=2 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 74, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 1.512 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.512 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG [Client 4c12a5ec49b51021] delay duration 4.522814256s client | 1.512 DEBUG Setting timeout of 4.522814256s client | 1.512 DEBUG [Client 4c12a5ec49b51021] pn=3 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 81 client | -> RX Crypto { offset: 688, len: 36 } client | 1.512 DEBUG [Agent 0x55b8da9da500] state -> AuthenticationPending client | 1.512 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 443, tv_nsec: 389198134 } client | 1.512 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.512 DEBUG [Client 4c12a5ec49b51021] pn=3 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 6, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 1.512 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.512 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.512 DEBUG [Client 4c12a5ec49b51021] delay duration 4.522677281s client | 1.512 DEBUG Setting timeout of 4.522677281s client | 1.512 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 1.513 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.513 DEBUG [Client 4c12a5ec49b51021] pn=4 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ping client | 1.513 DEBUG packet_sent this=0x55b8da9edaf0, pn=4, ps=41 client | 1.513 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.513 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG [Client 4c12a5ec49b51021] delay duration 4.523740311s client | 1.513 DEBUG Setting timeout of 4.523740311s client | 1.513 DEBUG StateChange(Handshaking) client | 1.513 WARN Cannot create stream ConnectionState client | 1.513 DEBUG stream BiDi creatable client | 1.513 WARN Cannot create stream ConnectionState client | 1.513 DEBUG stream UniDi creatable client | 1.513 DEBUG [Client 4c12a5ec49b51021] Authenticated Ok client | 1.513 DEBUG Write secret available for ApplicationData: SymKey [32]: 83bda33132ea529c0c38de194ef0a07479de451b4ad2e28b22045ebff89b1cb6 client | 1.513 DEBUG Read secret available for ApplicationData: SymKey [32]: 794c359825575c1f955092d2cca726bb0ed9eb0e6548266061e5940576837055 client | 1.513 DEBUG [Agent 0x55b8da9da500] SSL_AuthCertificateComplete: Ok(()) client | 1.513 DEBUG [Agent 0x55b8da9da500] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 1.513 DEBUG [Client 4c12a5ec49b51021] TLS connection complete client | 1.513 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 1.513 DEBUG [Crypto] Application write key installed client | 1.513 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 1.513 DEBUG [Crypto] application read keys installed client | 1.513 DEBUG [Client 4c12a5ec49b51021] State change from Handshaking -> Connected client | 1.513 INFO [Client 4c12a5ec49b51021] Connection established client | 1.513 DEBUG stream BiDi creatable client | 1.513 INFO Created stream 0 for https://server4/bnxibxxdry client | 1.513 INFO Saving https://server4/bnxibxxdry to "/downloads/bnxibxxdry" client | 1.513 DEBUG stream UniDi creatable client | 1.513 DEBUG StateChange(Connected) client | 1.513 DEBUG stream 0 writable client | 1.513 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.513 DEBUG CRYPTO for hs offset=0, len=36 client | 1.513 DEBUG [Client 4c12a5ec49b51021] pn=5 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 77 client | TX -> Crypto { offset: 0, len: 36 } client | 1.513 DEBUG packet_sent this=0x55b8da9edaf0, pn=5, ps=77 client | 1.513 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 1.513 DEBUG [Client 4c12a5ec49b51021] pn=0 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 127 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 1.513 DEBUG packet_sent this=0x55b8da9edaf0, pn=0, ps=50 client | 1.513 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.513 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 1.513 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG [Client 4c12a5ec49b51021] delay duration 4.523711167s client | 1.513 DEBUG Setting timeout of 4.523711167s client | 1.805 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 2 client | 1.805 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.805 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 1.805 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG [Client 4c12a5ec49b51021] delay duration 4.23220669s client | 1.805 DEBUG Setting timeout of 4.23220669s client | 1.805 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.805 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 1.805 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG [Client 4c12a5ec49b51021] delay duration 4.232167147s client | 1.805 DEBUG Setting timeout of 4.232167147s client | 1.805 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 3 client | 1.805 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.805 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 1.805 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG [Client 4c12a5ec49b51021] delay duration 4.231650198s client | 1.805 DEBUG Setting timeout of 4.231650198s client | 1.805 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 1.805 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 1.805 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG [Client 4c12a5ec49b51021] delay duration 4.231633176s client | 1.805 DEBUG Setting timeout of 4.231633176s client | 2.262 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 4 client | 2.262 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.262 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 2.262 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 2.262 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.262 DEBUG [Client 4c12a5ec49b51021] delay duration 3.774784532s client | 2.262 DEBUG Setting timeout of 3.774784532s client | 2.262 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.262 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 2.262 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 2.262 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.262 DEBUG [Client 4c12a5ec49b51021] delay duration 3.774733497s client | 2.262 DEBUG Setting timeout of 3.774733497s client | 2.263 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 5 client | 2.263 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.263 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 2.263 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 2.263 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.263 DEBUG [Client 4c12a5ec49b51021] delay duration 3.774108371s client | 2.263 DEBUG Setting timeout of 3.774108371s client | 2.263 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.263 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 2.263 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 2.263 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.263 DEBUG [Client 4c12a5ec49b51021] delay duration 3.774084547s client | 2.263 DEBUG Setting timeout of 3.774084547s server | xqc_hq_parse_req|247||hq recv CR LF| client | 2.406 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 6 client | 2.406 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.406 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 2.406 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 2.406 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.406 DEBUG [Client 4c12a5ec49b51021] delay duration 3.630321872s client | 2.406 DEBUG Setting timeout of 3.630321872s client | 2.407 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 4c12a5ec49b51021] delay duration 3.630284623s client | 2.407 DEBUG Setting timeout of 3.630284623s client | 2.407 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 7 client | 2.407 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 4c12a5ec49b51021] delay duration 3.629685428s client | 2.407 DEBUG Setting timeout of 3.629685428s client | 2.407 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 4c12a5ec49b51021] delay duration 3.629665401s client | 2.407 DEBUG Setting timeout of 3.629665401s client | 3.012 DEBUG [Client 4c12a5ec49b51021] pn=4 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 183 client | -> RX Crypto { offset: 0, len: 138 } client | 3.012 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 889240620 } client | 3.012 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.012 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 3.012 DEBUG CRYPTO for hs offset=0, len=36 client | 3.013 DEBUG [Client 4c12a5ec49b51021] pn=6 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 82 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 8, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 3.013 DEBUG packet_sent this=0x55b8da9edaf0, pn=6, ps=82 client | 3.013 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.013 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 3.013 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.013 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG [Client 4c12a5ec49b51021] delay duration 4.523677414s client | 3.013 DEBUG Setting timeout of 4.523677414s client | 3.013 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 3.013 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.013 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG [Client 4c12a5ec49b51021] delay duration 4.523639775s client | 3.013 DEBUG Setting timeout of 4.523639775s client | 3.013 DEBUG [Client 4c12a5ec49b51021] pn=5 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 517 client | -> RX Crypto { offset: 138, len: 471 } client | 3.013 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 889560938 } client | 3.013 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 3.013 DEBUG CRYPTO for hs offset=0, len=36 client | 3.013 DEBUG [Client 4c12a5ec49b51021] pn=7 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 82 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 4, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 3.013 DEBUG packet_sent this=0x55b8da9edaf0, pn=7, ps=82 client | 3.013 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.013 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 3.013 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.013 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG [Client 4c12a5ec49b51021] delay duration 4.523590277s client | 3.013 DEBUG Setting timeout of 4.523590277s client | 3.013 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 3.013 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.013 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.013 DEBUG [Client 4c12a5ec49b51021] delay duration 4.523561804s client | 3.013 DEBUG Setting timeout of 4.523561804s client | 3.014 DEBUG [Client 4c12a5ec49b51021] pn=6 type=Handshake pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 46 client | -> RX Ack { largest_acknowledged: 4, ack_delay: 11, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 3.014 DEBUG [Client 4c12a5ec49b51021] Rx ACK space=hs, ranges=[0..=4] client | 3.014 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=4 client | 3.014 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 291/13813 ssthresh 18446744073709551615] slow start += 41 client | 3.014 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 291/13813 ssthresh 18446744073709551615] on_packets_acked this=0x55b8da9edaf0, limited=0, bytes_in_flight=291, cwnd=13813, state=SlowStart, new_acked=41 client | 3.014 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.014 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 3.014 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.014 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.014 DEBUG [Client 4c12a5ec49b51021] delay duration 3.774051481s client | 3.014 DEBUG Setting timeout of 3.774051481s client | 3.014 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.014 DEBUG Building Handshake dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) scid Some(CID [0]: ) client | 3.014 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.014 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.014 DEBUG [Client 4c12a5ec49b51021] delay duration 3.774026314s client | 3.014 DEBUG Setting timeout of 3.774026314s client | 3.015 DEBUG [Client 4c12a5ec49b51021] pn=0 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 22 client | -> RX HandshakeDone client | 3.015 DEBUG [Client 4c12a5ec49b51021] State change from Connected -> Confirmed client | 3.015 DEBUG PMTUD started with probe size 1380 client | 3.015 DEBUG [Client 4c12a5ec49b51021] Drop packet number space hs client | 3.015 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 3.015 DEBUG [Client 4c12a5ec49b51021] No preferred address to migrate to client | 3.015 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 911662815 } client | 3.015 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.015 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.015 DEBUG Sending PMTUD probe of size 1380, count 1 client | 3.015 DEBUG [Client 4c12a5ec49b51021] pn=1 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ping client | TX -> Padding { len: 1321 } client | 3.015 DEBUG packet_sent this=0x55b8da9edaf0, pn=1, ps=1352 client | 3.015 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.015 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.015 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.015 DEBUG ECN probing: sent 1 probes client | 3.015 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.015 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.015 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.015 DEBUG [Client 4c12a5ec49b51021] delay duration 7.445219ms client | 3.015 DEBUG Setting timeout of 7.445219ms client | 3.015 DEBUG [Client 4c12a5ec49b51021] pn=1 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 32 client | -> RX NewToken { token: [0, 193, 167, 0, 100, 104, 204, 119, 199] } client | 3.015 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 891908149 } client | 3.015 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.015 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.015 DEBUG [Client 4c12a5ec49b51021] pn=2 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 3.015 DEBUG ECN probing: sent 2 probes client | 3.015 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.015 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.015 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.015 DEBUG [Client 4c12a5ec49b51021] delay duration 9.093521ms client | 3.015 DEBUG Setting timeout of 9.093521ms client | 3.015 DEBUG [Client 4c12a5ec49b51021] pn=2 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 282 client | -> RX Crypto { offset: 0, len: 257 } client | 3.015 DEBUG [0x55b8da9da500] Got resumption token [921]: 0200063e8d1ddb60..8f4148b1ffe358b2 client | 3.015 DEBUG [Agent 0x55b8da9da500] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 3.015 DEBUG setting max_stream_data to 16777216 client | 3.016 DEBUG resumption token [1026]: 0000000145e34057..8f4148b1ffe358b2 client | 3.016 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 912135001 } client | 3.016 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG [Client 4c12a5ec49b51021] delay duration 8.865033ms client | 3.016 DEBUG Setting timeout of 8.865033ms client | 3.016 DEBUG [Client 4c12a5ec49b51021] pn=3 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 283 client | -> RX Crypto { offset: 257, len: 257 } client | 3.016 DEBUG [0x55b8da9da500] Got resumption token [921]: 0200063e8d1ddb61..02ddf3d7201c3c3d client | 3.016 DEBUG [Agent 0x55b8da9da500] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 3.016 DEBUG setting max_stream_data to 16777216 client | 3.016 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 892362817 } client | 3.016 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.016 DEBUG [Client 4c12a5ec49b51021] pn=3 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 8, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 3.016 DEBUG ECN probing: sent 3 probes client | 3.016 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG [Client 4c12a5ec49b51021] delay duration 10.644555ms client | 3.016 DEBUG Setting timeout of 10.644555ms client | 3.016 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 8 client | 3.016 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG [Client 4c12a5ec49b51021] delay duration 10.618035ms client | 3.016 DEBUG Setting timeout of 10.618035ms client | 3.016 DEBUG [Client 4c12a5ec49b51021] pn=6 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 53 client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [93, 133, 29, 254, 21, 63, 103, 139, 15, 181, 254, 226], stateless_reset_token: [97, 129, 216, 127, 235, 131, 214, 58, 170, 210, 25, 154, 233, 140, 145, 47] } client | 3.016 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 892519759 } client | 3.016 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.016 DEBUG [Client 4c12a5ec49b51021] pn=4 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 37 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 2, first_ack_range: 0, ack_ranges: [AckRange { gap: 1, range: 3 }], ecn_count: None } client | 3.016 DEBUG ECN probing: sent 4 probes client | 3.016 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG [Client 4c12a5ec49b51021] delay duration 12.566117ms client | 3.016 DEBUG Setting timeout of 12.566117ms client | 3.016 DEBUG [Client 4c12a5ec49b51021] pn=7 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 53 client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [254, 165, 195, 86, 67, 154, 215, 63, 120, 146, 119, 220], stateless_reset_token: [131, 85, 165, 192, 246, 246, 244, 92, 219, 179, 41, 203, 73, 229, 220, 153] } client | 3.016 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 912597454 } client | 3.016 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG [Client 4c12a5ec49b51021] delay duration 12.52442ms client | 3.016 DEBUG Setting timeout of 12.52442ms client | 3.016 WARN Unhandled event StateChange(Confirmed) client | 3.016 DEBUG stream BiDi creatable client | 3.016 DEBUG stream UniDi creatable client | 3.016 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.016 DEBUG [Client 4c12a5ec49b51021] delay duration 12.502208ms client | 3.016 DEBUG Setting timeout of 12.502208ms client | 3.017 DEBUG [Client 4c12a5ec49b51021] pn=8 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, NotEct) len 1049 client | -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true } client | 3.017 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 893326850 } client | 3.017 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.017 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.017 DEBUG [Client 4c12a5ec49b51021] pn=5 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 37 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 4, first_ack_range: 2, ack_ranges: [AckRange { gap: 1, range: 3 }], ecn_count: None } client | 3.017 DEBUG ECN probing: sent 5 probes client | 3.017 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.017 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.017 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.017 DEBUG [Client 4c12a5ec49b51021] delay duration 13.743204ms client | 3.017 DEBUG Setting timeout of 13.743204ms client | 3.017 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.017 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.017 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.017 DEBUG [Client 4c12a5ec49b51021] delay duration 13.68751ms client | 3.017 DEBUG Setting timeout of 13.68751ms client | 3.017 DEBUG [Client 4c12a5ec49b51021] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 456, tv_nsec: 294683258 } } client | 3.017 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 456, tv_nsec: 294683258 } }) client | 3.017 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.017 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.017 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 444, tv_nsec: 893505179 } client | 3.017 DEBUG [Client 4c12a5ec49b51021] pn=6 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 48 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 22, first_ack_range: 2, ack_ranges: [AckRange { gap: 1, range: 3 }], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 3.017 DEBUG ECN probing: sent 6 probes client | 3.017 DEBUG Setting timeout of 11.401094754s client | 3.040 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.040 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 3.040 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 444, tv_nsec: 916688026 } client | 3.040 DEBUG [Client 4c12a5ec49b51021] pn=7 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 49 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 2920, first_ack_range: 2, ack_ranges: [AckRange { gap: 1, range: 3 }], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 3.040 DEBUG ECN probing: sent 7 probes client | 3.040 DEBUG Setting timeout of 11.377887542s client | 3.040 DEBUG Setting timeout of 11.377873646s client | 4.514 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.514 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 4.514 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 446, tv_nsec: 390689607 } client | 4.514 DEBUG [Client 4c12a5ec49b51021] pn=8 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 51 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 187170, first_ack_range: 2, ack_ranges: [AckRange { gap: 1, range: 3 }], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.514 DEBUG ECN probing: sent 8 probes client | 4.514 DEBUG Setting timeout of 9.903846738s client | 4.514 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.514 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 4.514 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 446, tv_nsec: 390854022 } client | 4.514 DEBUG [Client 4c12a5ec49b51021] pn=9 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 51 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 187190, first_ack_range: 2, ack_ranges: [AckRange { gap: 1, range: 3 }], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.514 DEBUG ECN probing: sent 9 probes client | 4.514 DEBUG Setting timeout of 9.903769524s client | 4.514 DEBUG Setting timeout of 9.903762s client | 4.518 DEBUG [Client 4c12a5ec49b51021] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.518 DEBUG Building Short dcid Some(CID [12]: 00c5738fb9d6d94621c0a105) client | 4.518 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 446, tv_nsec: 394499972 } client | 4.518 DEBUG [Client 4c12a5ec49b51021] pn=10 type=Short pri-path:00c5738fb9d6d94621c0a105 0.0.0.0:38683->193.167.100.100:443 Tos(Cs0, Ect0) len 51 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 187646, first_ack_range: 2, ack_ranges: [AckRange { gap: 1, range: 3 }], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.518 DEBUG ECN probing: sent 10 probes client | 4.518 DEBUG ECN probing concluded with 10 probes sent client | 4.518 DEBUG Setting timeout of 9.900096886s client | 4.518 DEBUG Setting timeout of 9.900089712s server | send_count:16, lost_count:0, tlp_count:1, recv_count:25, srtt:1502479 early_data_flag:0, conn_err:0, ack_info:#10-0#0-0#0-0#v0429, path_info:0/1/0/a/0,1,1,1,0,0,0,0,0,0,0,28,31,25,1024,0,17,i32,4,38,51,0,10,4509,0,4,38,51,0,8,4506,0,4,38,51,0,9,4506,0,4,9,40000,10,3009,2,30,4,8,3005,2,30,4,9,3005,0-2,0-2,0-0,0-0,0-0,0-1502,0-7501,0-11015,0-16,0-25,0-0,0-1,0-0,0-0,0-0,0-0,0-0,0-0 client | 14.419 DEBUG [Client 4c12a5ec49b51021] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 456, tv_nsec: 294683258 } } -> Closed(Application(0)) client | 14.419 INFO Closing timer expired client | 14.419 DEBUG Output::None client | 14.419 WARN Unhandled event StateChange(Closed(Application(0))) client | 14.419 DEBUG Timer fired while closed client | 14.419 DEBUG Output::None client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-09-11 21:21:34,359 Using the client's key log file. 2025-09-11 21:21:34,368 2025-09-11 21:21:34,369 Using the client's key log file. 2025-09-11 21:21:34,369 Using the client's key log file. 2025-09-11 21:21:34,377 2025-09-11 21:21:34,377 Using the client's key log file. 2025-09-11 21:21:34,624 Check of downloaded files succeeded. 2025-09-11 21:21:35,672 Check of downloaded files succeeded.