2025-04-30 11:42:51,885 Generated random file: fqjlcfioym of size: 10240 2025-04-30 11:42:51,885 Requests: https://server4:443/fqjlcfioym 2025-04-30 11:42:51,943 2025-04-30 11:42:51,944 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_w5xyhncd/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_jflcaij8/ DOWNLOADS=/tmp/download_f3re3xc_/ SERVER_LOGS=/tmp/logs_server_rufmv5vd CLIENT_LOGS=/tmp/logs_client_d0_ybz22 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/microsoft/msquic/qns:main REQUESTS="https://server4:443/fqjlcfioym" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 11:43:04,271 Container sim Created Container server Recreate Container client Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... server | Actual changes: server | tx-checksumming: off server | tx-checksum-ip-generic: off server | tx-checksum-sctp: off server | tcp-segmentation-offload: off server | tx-tcp-segmentation: off [requested on] server | tx-tcp-ecn-segmentation: off [requested on] server | tx-tcp-mangleid-segmentation: off [requested on] server | tx-tcp6-segmentation: off [requested on] server | tx-udp-segmentation: off [requested on] server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 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 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 | Enabling forced RETRY on server. server | Waiting forever. server | client | wait-for-it.sh: waiting 30 seconds for sim:57832 sim | server:443 is available after 1.040801603s 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/fqjlcfioym ']' 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/fqjlcfioym client | ' client | ++ tee -i -a /logs/client.log client | 0.000 DEBUG Logging initialized client | 0.001 DEBUG Default socket send buffer size is 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:46880 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=78780c2bdba0b1fe3e client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 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:46880->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:78780c2bdba0b1fe3e 0.0.0.0:46880->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:78780c2bdba0b1fe3e 0.0.0.0:46880->193.167.100.100:443] Path validated Instant { tv_sec: 367, tv_nsec: 431927224 } client | 0.001 DEBUG [Client 78780c2bdba0b1fe3e] client_start client | 0.001 DEBUG Writing transport parameters, msg=1 client | 0.001 DEBUG [Agent 0x557a240a5270] state -> InProgress client | 0.001 DEBUG [Client 78780c2bdba0b1fe3e] State change from Init -> WaitInitial client | 0.001 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.001 DEBUG Building Initial dcid Some(CID [9]: 78780c2bdba0b1fe3e) scid Some(CID [0]: ) client | 0.001 DEBUG CRYPTO for in offset=0, len=1212 client | 0.002 DEBUG [Client 78780c2bdba0b1fe3e] pn=0 type=Initial pri-path:78780c2bdba0b1fe3e 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1212 } client | 0.002 DEBUG packet_sent this=0x557a240b8970, pn=0, ps=1252 client | 0.002 DEBUG ECN probing: sent 1 probes client | 0.002 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 78780c2bdba0b1fe3e) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1212, len=327 client | 0.002 DEBUG [Client 78780c2bdba0b1fe3e] pn=1 type=Initial pri-path:78780c2bdba0b1fe3e 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 368 client | TX -> Crypto { offset: 1212, len: 327 } client | 0.002 DEBUG [Client 78780c2bdba0b1fe3e] pad Initial from 368 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x557a240b8970, pn=1, ps=1252 client | 0.002 DEBUG ECN probing: sent 2 probes client | 0.002 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 78780c2bdba0b1fe3e) 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 78780c2bdba0b1fe3e] delay duration 3.872434ms client | 0.002 DEBUG Setting timeout of 3.872434ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 78780c2bdba0b1fe3e) 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 78780c2bdba0b1fe3e] delay duration 3.84859ms client | 0.002 DEBUG Setting timeout of 3.84859ms client | 0.007 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [9]: 78780c2bdba0b1fe3e) 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 78780c2bdba0b1fe3e] delay duration 294.802858ms client | 0.007 DEBUG Setting timeout of 294.802858ms client | 0.038 INFO [Client 78780c2bdba0b1fe3e] received Retry client | 0.038 INFO [Client 78780c2bdba0b1fe3e] Valid Retry received, token=ae88030d2d0300002b924f0a480379e52ef6c5785e92b59e540002809069a51e14cc7831d866a4483e7fd3e69c70840e4d241d76932355de7d1b354cb1c59acf943b111608745f3c71d1806c00000000 scid=644d2c3a1714e6548f client | 0.038 INFO [pri-path:644d2c3a1714e6548f 0.0.0.0:46880->193.167.100.100:443] discarding a packet without an RTT estimate; guessing RTT=37.219483ms client | 0.038 DEBUG [Client 78780c2bdba0b1fe3e] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1212 }) client | 0.038 INFO Lost crypto frame space=in offset=0 length=1212 client | 0.038 DEBUG [Client 78780c2bdba0b1fe3e] Lost: EcnEct0 client | 0.038 DEBUG [Client 78780c2bdba0b1fe3e] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 327 }) client | 0.038 INFO Lost crypto frame space=in offset=1212 length=327 client | 0.038 DEBUG [Client 78780c2bdba0b1fe3e] Lost: EcnEct0 client | 0.038 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=644d2c3a1714e6548f client | 0.038 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 0.039 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 client | 0.039 INFO [CryptoStates] Continue packet numbers for initial after retry (write is 0..0) client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [9]: 644d2c3a1714e6548f) scid Some(CID [0]: ) client | 0.039 DEBUG CRYPTO for in offset=0, len=1131 client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] pn=2 type=Initial pri-path:644d2c3a1714e6548f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1131 } client | 0.039 DEBUG packet_sent this=0x557a240b8970, pn=2, ps=1252 client | 0.039 DEBUG ECN probing: sent 3 probes client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [9]: 644d2c3a1714e6548f) scid Some(CID [0]: ) client | 0.039 DEBUG CRYPTO for in offset=1131, len=408 client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] pn=3 type=Initial pri-path:644d2c3a1714e6548f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 530 client | TX -> Crypto { offset: 1131, len: 408 } client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] pad Initial from 530 to PLPMTU 1252 client | 0.039 DEBUG packet_sent this=0x557a240b8970, pn=3, ps=1252 client | 0.039 DEBUG ECN probing: sent 4 probes client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [9]: 644d2c3a1714e6548f) scid Some(CID [0]: ) client | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 111.578608ms client | 0.039 DEBUG Setting timeout of 111.578608ms client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [9]: 644d2c3a1714e6548f) scid Some(CID [0]: ) client | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 111.548142ms client | 0.039 DEBUG Setting timeout of 111.548142ms client | 0.073 DEBUG [Client 78780c2bdba0b1fe3e] pn=0 type=Initial pri-path:644d2c3a1714e6548f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 141 client | -> RX Ack { largest_acknowledged: 3, ack_delay: 2, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | -> RX Crypto { offset: 0, len: 90 } client | 0.073 DEBUG [Client 78780c2bdba0b1fe3e] Rx ACK space=in, ranges=[2..=3] client | 0.073 DEBUG [LossRecovery] ACK for Initial - largest_acked=3 client | 0.073 DEBUG on_packets_acked this=0x557a240b8970, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=2504 client | 0.073 DEBUG Acked crypto frame space=in offset=1131 length=408 client | 0.073 DEBUG Acked crypto frame space=in offset=0 length=1131 client | 0.073 DEBUG Read Ok(90) bytes client | 0.073 DEBUG Write secret available for Handshake: SymKey [48]: eb2951cb4305d908cf86e6a541675cf2b10cee9a0bb1e829a5ec09a467bb624d9447161a40798795c6720d998462e642 client | 0.073 DEBUG Read secret available for Handshake: SymKey [48]: 91f7ada223f20a1cdec061042ae031650fdfae0f46c2176b8a4a9d2904afec537ce91bbaa6440638147647d9909047b2 client | 0.073 DEBUG [Agent 0x557a240a5270] state -> InProgress client | 0.073 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4866 client | 0.074 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4866 client | 0.074 DEBUG [Crypto] Handshake keys installed client | 0.074 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 504324680 } client | 0.074 DEBUG [Client 78780c2bdba0b1fe3e] Changing to use Server CID=389eecefb6cc178f2f client | 0.074 DEBUG [Client 78780c2bdba0b1fe3e] State change from WaitInitial -> Handshaking client | 0.074 DEBUG [Client 78780c2bdba0b1fe3e] pn=1 type=Handshake pri-path:389eecefb6cc178f2f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1079 client | -> RX Crypto { offset: 0, len: 745 } client | -> RX Padding { len: 292 } client | 0.074 DEBUG Read Ok(745) bytes client | 0.074 DEBUG [Agent 0x557a240a5270] state -> AuthenticationPending client | 0.074 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 504324680 } client | 0.074 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Initial dcid Some(CID [9]: 389eecefb6cc178f2f) scid Some(CID [0]: ) client | 0.074 DEBUG [Client 78780c2bdba0b1fe3e] pn=4 type=Initial pri-path:389eecefb6cc178f2f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 123 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 179, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.074 DEBUG Building Handshake dcid Some(CID [9]: 389eecefb6cc178f2f) scid Some(CID [0]: ) client | 0.074 DEBUG [Client 78780c2bdba0b1fe3e] pn=0 type=Handshake pri-path:389eecefb6cc178f2f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 164 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 179, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.074 DEBUG [Client 78780c2bdba0b1fe3e] Drop packet number space in client | 0.074 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 0.074 WARN [LossRecovery] ignoring in-4 from dropped space client | 0.074 DEBUG ECN probing: sent 5 probes client | 0.074 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [9]: 389eecefb6cc178f2f) scid Some(CID [0]: ) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 95.620834ms client | 0.075 DEBUG Setting timeout of 95.620834ms client | 0.075 DEBUG StateChange(Handshaking) client | 0.075 WARN Cannot create stream ConnectionState client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] Authenticated Ok client | 0.075 DEBUG Write secret available for ApplicationData: SymKey [48]: 1b859ce4e37ddad954be097ceabd9169c398fc43c5930139dcd151cb9b3e2fc507ed73cc95d81f9d3f6ffe8309d0751e client | 0.075 DEBUG Read secret available for ApplicationData: SymKey [48]: 7e4d29b8f796196d17e30328850f4b1b127656378acf270cea59b0d0f0a0988e92090824b9fa79965bc732378573d166 client | 0.075 DEBUG [Agent 0x557a240a5270] SSL_AuthCertificateComplete: Ok(()) client | 0.075 DEBUG [Agent 0x557a240a5270] state -> Complete(SecretAgentInfo { version: 772, cipher: 4866, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] TLS connection complete client | 0.075 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4866 client | 0.075 DEBUG [Crypto] Application write key installed client | 0.075 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4866 client | 0.075 DEBUG [Crypto] application read keys installed client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] State change from Handshaking -> Connected client | 0.075 INFO [Client 78780c2bdba0b1fe3e] Connection established client | 0.075 DEBUG stream BiDi creatable client | 0.075 INFO Created stream 0 for https://server4/fqjlcfioym client | 0.075 INFO Saving https://server4/fqjlcfioym to "/downloads/fqjlcfioym" client | 0.075 DEBUG stream UniDi creatable client | 0.075 DEBUG StateChange(Connected) client | 0.075 DEBUG stream 0 writable client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [9]: 389eecefb6cc178f2f) scid Some(CID [0]: ) client | 0.075 DEBUG CRYPTO for hs offset=0, len=52 client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] pn=1 type=Handshake pri-path:389eecefb6cc178f2f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 90 client | TX -> Crypto { offset: 0, len: 52 } client | 0.075 DEBUG packet_sent this=0x557a240b8970, pn=1, ps=90 client | 0.075 DEBUG Building Short dcid Some(CID [9]: 389eecefb6cc178f2f) client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] pn=0 type=Short pri-path:389eecefb6cc178f2f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 137 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.075 DEBUG packet_sent this=0x557a240b8970, pn=0, ps=47 client | 0.075 DEBUG ECN probing: sent 6 probes client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [9]: 389eecefb6cc178f2f) scid Some(CID [0]: ) client | 0.075 DEBUG Building Short dcid Some(CID [9]: 389eecefb6cc178f2f) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 95.640319ms client | 0.075 DEBUG Setting timeout of 95.640319ms server | [193.167.0.100:46880] GET '/fqjlcfioym' client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] pn=2 type=Short pri-path:389eecefb6cc178f2f 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [56, 158, 63, 169, 177, 144, 36, 242, 166], stateless_reset_token: [106, 70, 103, 109, 224, 14, 50, 13, 37, 254, 100, 31, 150, 86, 72, 214] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [56, 158, 219, 212, 111, 160, 16, 247, 167], stateless_reset_token: [12, 181, 223, 31, 143, 196, 145, 43, 240, 210, 128, 71, 243, 214, 67, 143] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [56, 158, 152, 79, 80, 36, 225, 20, 234], stateless_reset_token: [75, 98, 161, 216, 200, 151, 195, 53, 67, 200, 113, 228, 26, 161, 41, 159] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 1, connection_id: [202, 242, 4, 234, 229, 14, 17, 163, 20], stateless_reset_token: [112, 15, 188, 112, 135, 136, 185, 248, 98, 174, 50, 208, 168, 195, 74, 228] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 2, connection_id: [202, 242, 51, 91, 102, 1, 6, 253, 175], stateless_reset_token: [52, 185, 246, 106, 169, 215, 171, 170, 207, 55, 130, 1, 123, 156, 70, 228] } client | -> RX NewConnectionId { sequence_number: 6, retire_prior: 3, connection_id: [202, 242, 216, 27, 113, 63, 0, 144, 166], stateless_reset_token: [15, 68, 42, 242, 13, 50, 254, 57, 88, 58, 228, 28, 130, 170, 84, 109] } client | -> RX NewConnectionId { sequence_number: 7, retire_prior: 4, connection_id: [202, 242, 141, 93, 60, 128, 126, 64, 250], stateless_reset_token: [49, 106, 83, 200, 123, 148, 29, 195, 221, 20, 246, 88, 198, 175, 200, 86] } client | -> RX Padding { len: 987 } client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] Rx ACK space=ap, ranges=[0..=0] client | 0.107 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 client | 0.107 DEBUG on_packets_acked this=0x557a240b8970, limited=1, bytes_in_flight=90, cwnd=12520, state=SlowStart, new_acked=47 client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] State change from Connected -> Confirmed client | 0.107 DEBUG PMTUD started with probe size 1380 client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] Drop packet number space hs client | 0.107 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] No preferred address to migrate to client | 0.107 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 538106200 } client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.107 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] pn=1 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 19, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> Ping client | TX -> RetireConnectionId { sequence_number: 3 } client | TX -> RetireConnectionId { sequence_number: 2 } client | TX -> RetireConnectionId { sequence_number: 1 } client | TX -> RetireConnectionId { sequence_number: 0 } client | TX -> Padding { len: 1311 } client | 0.107 DEBUG packet_sent this=0x557a240b8970, pn=1, ps=1352 client | 0.107 DEBUG ECN probing: sent 7 probes client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.107 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 111.364806ms client | 0.107 DEBUG Setting timeout of 111.364806ms client | 0.107 DEBUG stream 0 complete client | 0.107 WARN Unhandled event StateChange(Confirmed) client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.107 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 111.325573ms client | 0.107 DEBUG Setting timeout of 111.325573ms client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] pn=3 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Ping client | -> RX Padding { len: 1198 } client | 0.108 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 367, tv_nsec: 539075426 } client | 0.108 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 539075426 } client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] pn=2 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 32 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 4, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.108 DEBUG ECN probing: sent 8 probes client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 110.562461ms client | 0.108 DEBUG Setting timeout of 110.562461ms client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 110.536763ms client | 0.108 DEBUG Setting timeout of 110.536763ms client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] pn=4 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 346 client | -> RX Crypto { offset: 0, len: 321 } client | 0.108 DEBUG Read Ok(321) bytes client | 0.108 DEBUG [0x557a240a5270] Got resumption token [985]: 02000633fd6ae840..09eb97ab920a8351 client | 0.108 DEBUG [Agent 0x557a240a5270] state -> Complete(SecretAgentInfo { version: 772, cipher: 4866, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.108 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 559347674 } client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.850171ms client | 0.108 DEBUG Setting timeout of 19.850171ms client | 0.108 DEBUG stream BiDi creatable client | 0.108 DEBUG stream UniDi creatable client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.824463ms client | 0.108 DEBUG Setting timeout of 19.824463ms client | 0.109 DEBUG [Client 78780c2bdba0b1fe3e] pn=5 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Stream { stream_id: 0, offset: 0, len: 1195, fin: false } client | 0.109 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 540338680 } client | 0.109 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.109 DEBUG [Client 78780c2bdba0b1fe3e] pn=3 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 32 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 0.109 DEBUG ECN probing: sent 9 probes client | 0.109 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 109.319245ms client | 0.109 DEBUG Setting timeout of 109.319245ms client | 0.109 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 109.29015ms client | 0.109 DEBUG Setting timeout of 109.29015ms client | 0.110 DEBUG [Client 78780c2bdba0b1fe3e] pn=6 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Stream { stream_id: 0, offset: 1195, len: 1193, fin: false } client | 0.110 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 561329044 } client | 0.110 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.110 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.110 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.110 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.926884ms client | 0.110 DEBUG Setting timeout of 19.926884ms client | 0.110 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.110 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.110 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.110 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.904162ms client | 0.110 DEBUG Setting timeout of 19.904162ms client | 0.111 DEBUG [Client 78780c2bdba0b1fe3e] pn=7 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Stream { stream_id: 0, offset: 2388, len: 1193, fin: false } client | 0.111 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 542327153 } client | 0.111 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.111 DEBUG [Client 78780c2bdba0b1fe3e] pn=4 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, Ect0) len 32 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 3, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 0.111 DEBUG ECN probing: sent 10 probes client | 0.111 DEBUG ECN probing concluded with 10 probes sent client | 0.111 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 107.332495ms client | 0.111 DEBUG Setting timeout of 107.332495ms client | 0.111 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 107.30838ms client | 0.111 DEBUG Setting timeout of 107.30838ms client | 0.112 DEBUG [Client 78780c2bdba0b1fe3e] pn=8 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Stream { stream_id: 0, offset: 3581, len: 1193, fin: false } client | 0.112 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 563325683 } client | 0.112 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.112 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.973921ms client | 0.112 DEBUG Setting timeout of 19.973921ms client | 0.112 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.112 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.953172ms client | 0.112 DEBUG Setting timeout of 19.953172ms client | 0.113 DEBUG [Client 78780c2bdba0b1fe3e] pn=9 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Stream { stream_id: 0, offset: 4774, len: 1193, fin: false } client | 0.113 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 544328122 } client | 0.113 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.113 DEBUG [Client 78780c2bdba0b1fe3e] pn=5 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 32 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 3, first_ack_range: 7, ack_ranges: [], ecn_count: None } client | 0.113 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.113 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 105.332528ms client | 0.113 DEBUG Setting timeout of 105.332528ms client | 0.113 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.113 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 105.30686ms client | 0.113 DEBUG Setting timeout of 105.30686ms client | 0.114 DEBUG [Client 78780c2bdba0b1fe3e] pn=10 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Stream { stream_id: 0, offset: 5967, len: 1193, fin: false } client | 0.114 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 565327974 } client | 0.114 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.114 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.114 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.114 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.974822ms client | 0.114 DEBUG Setting timeout of 19.974822ms client | 0.114 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.114 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.114 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.114 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.947872ms client | 0.114 DEBUG Setting timeout of 19.947872ms client | 0.115 DEBUG [Client 78780c2bdba0b1fe3e] pn=11 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Stream { stream_id: 0, offset: 7160, len: 1193, fin: false } client | 0.115 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 546328599 } client | 0.115 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.115 DEBUG [Client 78780c2bdba0b1fe3e] pn=6 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 32 client | TX -> Ack { largest_acknowledged: 11, ack_delay: 3, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | 0.115 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 103.332632ms client | 0.115 DEBUG Setting timeout of 103.332632ms client | 0.115 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 103.305903ms client | 0.115 DEBUG Setting timeout of 103.305903ms client | 0.116 DEBUG [Client 78780c2bdba0b1fe3e] pn=12 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 1220 client | -> RX Stream { stream_id: 0, offset: 8353, len: 1193, fin: false } client | 0.116 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 567327459 } client | 0.116 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.97364ms client | 0.116 DEBUG Setting timeout of 19.97364ms client | 0.116 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 19.952972ms client | 0.116 DEBUG Setting timeout of 19.952972ms client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] pn=13 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 721 client | -> RX Stream { stream_id: 0, offset: 9546, len: 694, fin: true } client | 0.117 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 367, tv_nsec: 547930152 } client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] pn=7 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 32 client | TX -> Ack { largest_acknowledged: 13, ack_delay: 3, first_ack_range: 11, ack_ranges: [], ecn_count: None } client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.117 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 101.707335ms client | 0.117 DEBUG Setting timeout of 101.707335ms client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.117 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] delay duration 101.649507ms client | 0.117 DEBUG Setting timeout of 101.649507ms client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 367, tv_nsec: 882544390 } } client | 0.117 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 367, tv_nsec: 882544390 } }) client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.117 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 367, tv_nsec: 548121789 } client | 0.117 DEBUG [Client 78780c2bdba0b1fe3e] pn=8 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 13, ack_delay: 23, first_ack_range: 11, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.117 DEBUG Setting timeout of 334.344777ms client | 0.140 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.140 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.140 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 367, tv_nsec: 570903148 } client | 0.140 DEBUG [Client 78780c2bdba0b1fe3e] pn=9 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 13, ack_delay: 2871, first_ack_range: 11, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.140 DEBUG Setting timeout of 311.563758ms client | 0.140 DEBUG Setting timeout of 311.554952ms client | 0.147 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.147 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.147 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 367, tv_nsec: 578459564 } client | 0.147 DEBUG [Client 78780c2bdba0b1fe3e] pn=10 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 13, ack_delay: 3816, first_ack_range: 11, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.147 DEBUG Setting timeout of 303.994968ms client | 0.147 DEBUG Setting timeout of 303.983086ms client | 0.147 DEBUG [Client 78780c2bdba0b1fe3e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.147 DEBUG Building Short dcid Some(CID [9]: caf204eae50e11a314) client | 0.147 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 367, tv_nsec: 578576011 } client | 0.147 DEBUG [Client 78780c2bdba0b1fe3e] pn=11 type=Short pri-path:caf204eae50e11a314 0.0.0.0:46880->193.167.100.100:443 IpTos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 13, ack_delay: 3830, first_ack_range: 11, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.147 DEBUG Setting timeout of 303.903147ms client | 0.147 DEBUG Setting timeout of 303.891776ms client | 0.452 DEBUG [Client 78780c2bdba0b1fe3e] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 367, tv_nsec: 882544390 } } -> Closed(Application(0)) client | 0.452 INFO Closing timer expired client | 0.452 DEBUG Output::None client | 0.452 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.452 DEBUG Timer fired while closed client | 0.452 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-04-30 11:43:04,364 Using the client's key log file. 2025-04-30 11:43:04,373 2025-04-30 11:43:04,373 Using the client's key log file. 2025-04-30 11:43:04,374 Using the client's key log file. 2025-04-30 11:43:04,382 2025-04-30 11:43:04,382 Using the client's key log file. 2025-04-30 11:43:04,939 Check of downloaded files succeeded. 2025-04-30 11:43:05,513 Check of Retry succeeded. Token used: ae88030d2d0300002b924f0a480379e52ef6c5785e92b59e540002809069a51e14cc7831d866a4483e7fd3e69c70840e4d241d76932355de7d1b354cb1c59acf943b111608745f3c71d1806c00000000