2025-09-11 17:09:56,046 Generated random file: tgetbduond of size: 10240 2025-09-11 17:09:56,046 Requests: https://server4:443/tgetbduond 2025-09-11 17:09:56,104 2025-09-11 17:09:56,104 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_p9feyf9b/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_rnanpjtl/ DOWNLOADS=/tmp/download_6qenkd0n/ SERVER_LOGS=/tmp/logs_server_ikfn5idh CLIENT_LOGS=/tmp/logs_client_wuswb_iw SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=peterdoornbosch/kwik_n_flupke-interop REQUESTS="https://server4:443/tgetbduond" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-11 17:10:09,636 Container server Recreate Container client Recreate Container server Recreated Container client 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 server | Setting up routes... client | Endpoint's IPv6 address is fd00:cafe:cafe::100 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 retry ']' 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 | Importing keystore /server.p12 to /server.keystore... server | running kwik server version 0.10.6 server | Old Flupke plugin not found sim | server:443 is available after 1.004593258s sim | Using scenario: simple-p2p --delay=15ms --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/tgetbduond ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test retry --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/tgetbduond 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:40052 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=ad3fac4a7bcef5f2 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=20 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:40052->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:ad3fac4a7bcef5f2 0.0.0.0:40052->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:ad3fac4a7bcef5f2 0.0.0.0:40052->193.167.100.100:443] Path validated Instant { tv_sec: 665, tv_nsec: 504049520 } client | 0.001 DEBUG [Client ad3fac4a7bcef5f2] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x55b451d5e500] state -> InProgress client | 0.002 DEBUG [Client ad3fac4a7bcef5f2] State change from Init -> WaitInitial client | 0.002 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: ad3fac4a7bcef5f2) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1213 client | 0.002 DEBUG [Client ad3fac4a7bcef5f2] pn=20 type=Initial pri-path:ad3fac4a7bcef5f2 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.002 DEBUG packet_sent this=0x55b451d71af0, pn=20, ps=1252 client | 0.002 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: ad3fac4a7bcef5f2) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=327 client | 0.002 DEBUG [Client ad3fac4a7bcef5f2] pn=21 type=Initial pri-path:ad3fac4a7bcef5f2 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 367 client | TX -> Crypto { offset: 1213, len: 327 } client | 0.002 DEBUG [Client ad3fac4a7bcef5f2] pad Initial from 367 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55b451d71af0, pn=21, ps=1252 client | 0.002 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: ad3fac4a7bcef5f2) 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 ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: ad3fac4a7bcef5f2) 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 ad3fac4a7bcef5f2] delay duration 3.929605ms client | 0.002 DEBUG Setting timeout of 3.929605ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: ad3fac4a7bcef5f2) 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 ad3fac4a7bcef5f2] delay duration 3.896934ms client | 0.002 DEBUG Setting timeout of 3.896934ms client | 0.007 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [8]: ad3fac4a7bcef5f2) 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 ad3fac4a7bcef5f2] delay duration 294.357075ms client | 0.007 DEBUG Setting timeout of 294.357075ms client | 0.145 INFO [Client ad3fac4a7bcef5f2] received Retry client | 0.145 INFO [Client ad3fac4a7bcef5f2] Valid Retry received, token=8769a5d239474e53c89e6d92cbf1a88dd9a7199f03850e0e858f9160ec3e325dbd158894ff scid=5474c5ecf6b68122 client | 0.145 INFO [pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443] discarding a packet without an RTT estimate; guessing RTT=143.267145ms client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.145 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 327 }) client | 0.145 INFO Lost crypto frame space=in offset=1213 length=327 client | 0.145 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=5474c5ecf6b68122 client | 0.145 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.145 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.145 INFO [CryptoStates] Continue packet numbers for initial after retry (write is 0..0) client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.145 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.145 DEBUG CRYPTO for in offset=0, len=1176 client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] pn=22 type=Initial pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1176 } client | 0.145 DEBUG packet_sent this=0x55b451d71af0, pn=22, ps=1252 client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.145 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.145 DEBUG CRYPTO for in offset=1176, len=364 client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] pn=23 type=Initial pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 441 client | TX -> Crypto { offset: 1176, len: 364 } client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] pad Initial from 441 to PLPMTU 1252 client | 0.145 DEBUG packet_sent this=0x55b451d71af0, pn=23, ps=1252 client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.145 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.145 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.145 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.145 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] delay duration 429.66578ms client | 0.145 DEBUG Setting timeout of 429.66578ms client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.145 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.145 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.145 DEBUG [Client ad3fac4a7bcef5f2] delay duration 429.63887ms client | 0.145 DEBUG Setting timeout of 429.63887ms client | 0.149 INFO [Client ad3fac4a7bcef5f2] received Retry client | 0.149 DEBUG [Client ...] Dropped received packet: Extra Retry; Total: 1 client | 0.149 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.149 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.149 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.149 DEBUG [Client ad3fac4a7bcef5f2] delay duration 425.671057ms client | 0.149 DEBUG Setting timeout of 425.671057ms client | 0.149 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.149 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.149 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.149 DEBUG [Client ad3fac4a7bcef5f2] delay duration 425.646812ms client | 0.149 DEBUG Setting timeout of 425.646812ms client | 0.186 DEBUG [Client ad3fac4a7bcef5f2] pn=0 type=Initial pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1201 client | -> RX Ack { largest_acknowledged: 22, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX Padding { len: 1161 } client | 0.186 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=in, ranges=[22..=22] client | 0.186 DEBUG [recovery::Loss] ACK for Initial - largest_acked=22 client | 0.186 DEBUG on_packets_acked this=0x55b451d71af0, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252 client | 0.186 DEBUG Acked crypto frame space=in offset=0 length=1176 client | 0.186 DEBUG [Client ad3fac4a7bcef5f2] Changing to use Server CID=5474c5ecf6b68122 client | 0.186 DEBUG [Client ad3fac4a7bcef5f2] State change from WaitInitial -> WaitVersion client | 0.186 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.186 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.186 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.186 DEBUG [Client ad3fac4a7bcef5f2] delay duration 407.100957ms client | 0.186 DEBUG Setting timeout of 407.100957ms client | 0.186 WARN Unhandled event StateChange(WaitVersion) client | 0.186 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.186 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.186 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.186 DEBUG [Client ad3fac4a7bcef5f2] delay duration 407.063818ms client | 0.186 DEBUG Setting timeout of 407.063818ms client | 0.314 DEBUG [Client ad3fac4a7bcef5f2] pn=1 type=Initial pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 454 client | -> RX Ack { largest_acknowledged: 23, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Padding { len: 320 } client | 0.314 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=in, ranges=[22..=23] client | 0.314 DEBUG [recovery::Loss] ACK for Initial - largest_acked=23 client | 0.314 DEBUG on_packets_acked this=0x55b451d71af0, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=1252 client | 0.314 DEBUG Acked crypto frame space=in offset=1176 length=364 client | 0.314 DEBUG Write secret available for Handshake: SymKey [32]: 5947ed5c38c9ac07cb9f3a67e929d9cc555f05bfacba7d675532242f060eccbf client | 0.314 DEBUG Read secret available for Handshake: SymKey [32]: da595b740d949ce85c37586d1fece8806c491f125cfee5c0fdd9bdcd84c850bd client | 0.314 DEBUG [Agent 0x55b451d5e500] state -> InProgress client | 0.314 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.314 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.314 DEBUG [Crypto] Handshake keys installed client | 0.314 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 816792420 } client | 0.314 DEBUG [Client ad3fac4a7bcef5f2] State change from WaitVersion -> Handshaking client | 0.314 DEBUG [Client ad3fac4a7bcef5f2] pn=0 type=Handshake pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 746 client | -> RX Crypto { offset: 0, len: 708 } client | 0.315 DEBUG [Agent 0x55b451d5e500] state -> AuthenticationPending client | 0.315 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 816792420 } client | 0.315 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.315 DEBUG Building Initial dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.316 DEBUG [Client ad3fac4a7bcef5f2] pn=24 type=Initial pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 78 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 231, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.316 DEBUG Building Handshake dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.316 DEBUG [Client ad3fac4a7bcef5f2] pn=0 type=Handshake pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 118 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 231, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.316 DEBUG [Client ad3fac4a7bcef5f2] Drop packet number space in client | 0.316 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 0.316 INFO [recovery::Loss] ignoring in-24 from dropped space client | 0.316 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.316 DEBUG Building Handshake dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.316 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.316 DEBUG [Client ad3fac4a7bcef5f2] delay duration 410.960701ms client | 0.316 DEBUG Setting timeout of 410.960701ms client | 0.316 DEBUG StateChange(Handshaking) client | 0.316 WARN Cannot create stream ConnectionState client | 0.316 DEBUG [Client ad3fac4a7bcef5f2] Authenticated Ok client | 0.316 DEBUG Write secret available for ApplicationData: SymKey [32]: 7e45cc69e96350986df92ee81ddfc541b0553309a6fc31950b682f33b211c768 client | 0.316 DEBUG Read secret available for ApplicationData: SymKey [32]: 1bd4d3b8f4f99b34b4d0b330fdeca8e9f30180290d1c154720aa13b44af7aa22 client | 0.316 DEBUG [Agent 0x55b451d5e500] SSL_AuthCertificateComplete: Ok(()) client | 0.316 DEBUG [Agent 0x55b451d5e500] 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 | 0.316 DEBUG [Client ad3fac4a7bcef5f2] TLS connection complete client | 0.316 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.316 DEBUG [Crypto] Application write key installed client | 0.316 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.316 DEBUG [Crypto] application read keys installed client | 0.316 DEBUG [Client ad3fac4a7bcef5f2] State change from Handshaking -> Connected client | 0.316 INFO [Client ad3fac4a7bcef5f2] Connection established client | 0.316 DEBUG stream BiDi creatable client | 0.316 INFO Created stream 0 for https://server4/tgetbduond client | 0.316 INFO Saving https://server4/tgetbduond to "/downloads/tgetbduond" client | 0.317 DEBUG StateChange(Connected) client | 0.317 DEBUG stream 0 writable client | 0.317 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.317 DEBUG Building Handshake dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.317 DEBUG CRYPTO for hs offset=0, len=36 client | 0.317 DEBUG [Client ad3fac4a7bcef5f2] pn=1 type=Handshake pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 0.317 DEBUG packet_sent this=0x55b451d71af0, pn=1, ps=73 client | 0.317 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.317 DEBUG [Client ad3fac4a7bcef5f2] pn=0 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 119 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.317 DEBUG packet_sent this=0x55b451d71af0, pn=0, ps=46 client | 0.317 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.317 DEBUG Building Handshake dcid Some(CID [8]: 5474c5ecf6b68122) scid Some(CID [0]: ) client | 0.317 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.317 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.317 DEBUG [Client ad3fac4a7bcef5f2] delay duration 411.225725ms client | 0.317 DEBUG Setting timeout of 411.225725ms client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] pn=0 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 204 client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [116, 76, 136, 10, 247, 209, 90, 252], stateless_reset_token: [215, 195, 26, 158, 253, 155, 105, 244, 155, 22, 106, 81, 127, 169, 116, 132] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [225, 88, 33, 124, 30, 199, 161, 158], stateless_reset_token: [46, 31, 30, 46, 19, 242, 246, 142, 30, 78, 211, 143, 211, 203, 208, 229] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [217, 231, 96, 44, 85, 176, 243, 42], stateless_reset_token: [192, 134, 248, 144, 53, 1, 138, 167, 245, 15, 85, 235, 221, 34, 74, 248] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [144, 28, 142, 243, 8, 207, 79, 236], stateless_reset_token: [114, 243, 11, 198, 23, 102, 32, 195, 146, 227, 66, 200, 79, 123, 202, 239] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [226, 192, 198, 111, 89, 82, 48, 93], stateless_reset_token: [4, 211, 252, 78, 70, 122, 159, 173, 208, 206, 250, 139, 219, 157, 128, 105] } client | -> RX Crypto { offset: 0, len: 42 } client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] State change from Connected -> Confirmed client | 0.364 DEBUG PMTUD started with probe size 1380 client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] Drop packet number space hs client | 0.364 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] No preferred address to migrate to client | 0.364 DEBUG [0x55b451d5e500] Got resumption token [712]: 0200063e899b57a4..5402c6342a3bd264 client | 0.364 DEBUG [Agent 0x55b451d5e500] 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 | 0.364 DEBUG setting max_stream_data to 1000000 client | 0.364 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 886817286 } client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.364 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.364 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] pn=1 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ping client | TX -> Padding { len: 1325 } client | 0.364 DEBUG packet_sent this=0x55b451d71af0, pn=1, ps=1352 client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.364 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.364 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.364 DEBUG ECN probing: sent 1 probes client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.364 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.364 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.624489ms client | 0.364 DEBUG Setting timeout of 19.624489ms client | 0.364 WARN Unhandled event StateChange(Confirmed) client | 0.364 DEBUG stream BiDi creatable client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.364 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.364 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.364 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.59804ms client | 0.364 DEBUG Setting timeout of 19.59804ms client | 0.373 DEBUG [Client ad3fac4a7bcef5f2] pn=1 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 0, len: 1223, fin: false } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 250, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.373 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.373 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=0 client | 0.373 DEBUG on_packets_acked this=0x55b451d71af0, limited=1, bytes_in_flight=1352, cwnd=12520, state=SlowStart, new_acked=46 client | 0.373 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 876348448 } client | 0.373 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.373 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.373 DEBUG [Client ad3fac4a7bcef5f2] pn=2 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 9, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.373 DEBUG ECN probing: sent 2 probes client | 0.373 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.373 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.373 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.373 DEBUG [Client ad3fac4a7bcef5f2] delay duration 428.608414ms client | 0.373 DEBUG Setting timeout of 428.608414ms client | 0.373 DEBUG stream 0 complete client | 0.373 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.373 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.373 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.373 DEBUG [Client ad3fac4a7bcef5f2] delay duration 428.582966ms client | 0.373 DEBUG Setting timeout of 428.582966ms client | 0.374 DEBUG [Client ad3fac4a7bcef5f2] pn=2 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 1223, len: 1223, fin: false } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.374 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.374 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 897385853 } client | 0.374 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.374 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.374 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.374 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.961779ms client | 0.374 DEBUG Setting timeout of 19.961779ms client | 0.374 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.374 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.374 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.374 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.943074ms client | 0.374 DEBUG Setting timeout of 19.943074ms client | 0.375 DEBUG [Client ad3fac4a7bcef5f2] pn=3 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 2446, len: 1223, fin: false } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.375 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.375 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 878413831 } client | 0.375 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.375 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.375 DEBUG [Client ad3fac4a7bcef5f2] pn=3 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 4, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 0.375 DEBUG ECN probing: sent 3 probes client | 0.375 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.375 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.375 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.375 DEBUG [Client ad3fac4a7bcef5f2] delay duration 426.593084ms client | 0.375 DEBUG Setting timeout of 426.593084ms client | 0.375 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.375 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.375 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.375 DEBUG [Client ad3fac4a7bcef5f2] delay duration 426.573808ms client | 0.375 DEBUG Setting timeout of 426.573808ms client | 0.376 DEBUG [Client ad3fac4a7bcef5f2] pn=4 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 3669, len: 1223, fin: false } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.376 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.376 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 899443422 } client | 0.376 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.376 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.376 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.376 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.929789ms client | 0.376 DEBUG Setting timeout of 19.929789ms client | 0.376 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.376 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.376 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.376 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.910694ms client | 0.376 DEBUG Setting timeout of 19.910694ms client | 0.377 DEBUG [Client ad3fac4a7bcef5f2] pn=5 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 4892, len: 1223, fin: false } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.377 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.377 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 880421837 } client | 0.377 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.377 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.377 DEBUG [Client ad3fac4a7bcef5f2] pn=4 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 4, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 0.377 DEBUG ECN probing: sent 4 probes client | 0.377 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.377 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.377 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.377 DEBUG [Client ad3fac4a7bcef5f2] delay duration 424.591279ms client | 0.377 DEBUG Setting timeout of 424.591279ms client | 0.377 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.377 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.377 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.377 DEBUG [Client ad3fac4a7bcef5f2] delay duration 424.572184ms client | 0.377 DEBUG Setting timeout of 424.572184ms client | 0.378 DEBUG [Client ad3fac4a7bcef5f2] pn=6 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 6115, len: 1223, fin: false } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.378 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.378 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 901486533 } client | 0.378 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.378 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.378 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.378 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.961879ms client | 0.378 DEBUG Setting timeout of 19.961879ms client | 0.378 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.378 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.378 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.378 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.941791ms client | 0.378 DEBUG Setting timeout of 19.941791ms client | 0.379 DEBUG [Client ad3fac4a7bcef5f2] pn=7 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 7338, len: 1223, fin: false } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.379 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.379 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 882515673 } client | 0.379 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.379 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.379 DEBUG [Client ad3fac4a7bcef5f2] pn=5 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 4, first_ack_range: 7, ack_ranges: [], ecn_count: None } client | 0.379 DEBUG ECN probing: sent 5 probes client | 0.379 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.379 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.379 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.379 DEBUG [Client ad3fac4a7bcef5f2] delay duration 422.497644ms client | 0.379 DEBUG Setting timeout of 422.497644ms client | 0.379 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.379 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.379 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.379 DEBUG [Client ad3fac4a7bcef5f2] delay duration 422.478698ms client | 0.379 DEBUG Setting timeout of 422.478698ms client | 0.380 DEBUG [Client ad3fac4a7bcef5f2] pn=8 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 8561, len: 1223, fin: false } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.380 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.380 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 903551345 } client | 0.380 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.380 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.380 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.380 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.962029ms client | 0.380 DEBUG Setting timeout of 19.962029ms client | 0.380 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.380 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.380 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.380 DEBUG [Client ad3fac4a7bcef5f2] delay duration 19.937483ms client | 0.380 DEBUG Setting timeout of 19.937483ms client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] pn=9 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, NotEct) len 488 client | -> RX Stream { stream_id: 0, offset: 9784, len: 456, fin: true } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX MaxStreams { stream_type: BiDi, maximum_streams: 101 } client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] Rx ACK space=ap, ranges=[0..=0] client | 0.381 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 665, tv_nsec: 883948676 } client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.381 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] pn=6 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 4, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | 0.381 DEBUG ECN probing: sent 6 probes client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.381 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.381 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] delay duration 421.049482ms client | 0.381 DEBUG Setting timeout of 421.049482ms client | 0.381 DEBUG stream BiDi creatable client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.381 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.381 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] delay duration 420.998878ms client | 0.381 DEBUG Setting timeout of 420.998878ms client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 667, tv_nsec: 198078132 } } client | 0.381 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 667, tv_nsec: 198078132 } }) client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.381 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.381 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 665, tv_nsec: 884118774 } client | 0.381 DEBUG [Client ad3fac4a7bcef5f2] pn=7 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 42 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 21, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.381 DEBUG ECN probing: sent 7 probes client | 0.381 DEBUG Setting timeout of 1.313918071s client | 0.418 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.418 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.418 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 665, tv_nsec: 921517996 } client | 0.418 DEBUG [Client ad3fac4a7bcef5f2] pn=8 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 4696, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.418 DEBUG ECN probing: sent 8 probes client | 0.418 DEBUG Setting timeout of 1.276434301s client | 0.418 DEBUG Setting timeout of 1.276424413s client | 0.427 DEBUG [Client ad3fac4a7bcef5f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.427 DEBUG Building Short dcid Some(CID [8]: 5474c5ecf6b68122) client | 0.427 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 665, tv_nsec: 930708429 } client | 0.427 DEBUG [Client ad3fac4a7bcef5f2] pn=9 type=Short pri-path:5474c5ecf6b68122 0.0.0.0:40052->193.167.100.100:443 Tos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 5844, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.428 DEBUG ECN probing: sent 9 probes client | 0.428 DEBUG Setting timeout of 1.267243918s client | 0.428 DEBUG Setting timeout of 1.267229s server | QLog: done with ad3fac4a7bcef5f2.qlog client | 1.696 DEBUG [Client ad3fac4a7bcef5f2] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 667, tv_nsec: 198078132 } } -> Closed(Application(0)) client | 1.696 INFO Closing timer expired client | 1.696 DEBUG Output::None client | 1.696 WARN Unhandled event StateChange(Closed(Application(0))) client | 1.696 DEBUG Timer fired while closed client | 1.696 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 17:10:09,719 Using the client's key log file. 2025-09-11 17:10:09,728 2025-09-11 17:10:09,729 Using the client's key log file. 2025-09-11 17:10:09,729 Using the client's key log file. 2025-09-11 17:10:09,737 2025-09-11 17:10:09,738 Using the client's key log file. 2025-09-11 17:10:10,142 Check of downloaded files succeeded. 2025-09-11 17:10:10,554 Check of Retry succeeded. Token used: 8769a5d239474e53c89e6d92cbf1a88dd9a7199f03850e0e858f9160ec3e325dbd158894ff