2025-04-30 08:43:26,539 Generated random file: tfdhyesrfz of size: 5120 2025-04-30 08:43:26,539 Generated random file: axuayaqjgf of size: 10240 2025-04-30 08:43:26,539 Requests: https://server4:443/tfdhyesrfz https://server4:443/axuayaqjgf 2025-04-30 08:43:26,597 2025-04-30 08:43:26,598 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_bw2c1z66/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_1vsgu5ga/ DOWNLOADS=/tmp/download_ma2nb64p/ SERVER_LOGS=/tmp/logs_server_gsfmwfdi CLIENT_LOGS=/tmp/logs_client_qw15iaiq SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/facebook/proxygen/mvfst-interop:latest REQUESTS="https://server4:443/tfdhyesrfz https://server4:443/axuayaqjgf" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 08:43:39,250 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 | + set -o nounset server | + VERSION=1 server | + HQ_CLI=/proxygen/_build/proxygen/bin/hq server | + PORT=443 server | + LOGLEVEL=2 server | + /setup.sh 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 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 server | + PROTOCOL=hq-interop server | + HTTPVERSION=0.9 server | + CONN_FLOW_CONTROL=107374182 server | + STREAM_FLOW_CONTROL=107374182 server | ./run_endpoint.sh: line 28: REQUESTS: unbound variable server | ++ tr ' ' '\n' client | Endpoint's IPv4 address is 193.167.0.100 server | ++ awk -F / '{ print "/" $4 }' server | ++ paste -sd, server | + INVOCATIONS= server | + EARLYDATA=false server | + PSK_FILE= server | + '[' -n resumption ']' server | + case "${TESTCASE}" in server | + INVOCATIONS= server | + PSK_FILE=/psk server | + '[' server == client ']' server | + '[' server == server ']' server | + echo 'Running QUIC server on [::]:443' server | Running QUIC server on [::]:443 server | + /proxygen/_build/proxygen/bin/hq --mode=server --cert=/certs/cert.pem --key=/certs/priv.key --conn_flow_control=107374182 --stream_flow_control=107374182 --port=443 --httpversion=0.9 --h2port=443 --static_root=/www --logdir=/logs --qlogger_path=/logs --host=:: --congestion=bbr --pacing=true --v=2 server | + tee /logs/server.log 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 resumption ']' client | + '[' -n /logs/qlog/ ']' client | + case "$ROLE" in client | + /wait-for-it.sh sim:57832 -s -t 30 server | W20250430 08:43:27.177326 26 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:43:27.177376 26 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:43:27.177384 26 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:43:27.177408 26 QuicServerWorker.cpp:1234] HostId is already set to 0 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | I20250430 08:43:27.177663 26 HQServer.cpp:283] HQ server started at: [::]:443 sim | server:443 is available after 1.045367104s sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth0, 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/tfdhyesrfz https://server4:443/axuayaqjgf' ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test resumption --qlog-dir /logs/qlog/ --output-dir /downloads https://server4:443/tfdhyesrfz 'https://server4:443/axuayaqjgf 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:44822 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=6f16f998eee7d90c3b 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:44822->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:6f16f998eee7d90c3b 0.0.0.0:44822->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:6f16f998eee7d90c3b 0.0.0.0:44822->193.167.100.100:443] Path validated Instant { tv_sec: 272, tv_nsec: 515836116 } client | 0.001 DEBUG [Client 6f16f998eee7d90c3b] client_start client | 0.001 DEBUG Writing transport parameters, msg=1 client | 0.001 DEBUG [Agent 0x55f69074ff20] state -> InProgress client | 0.001 DEBUG [Client 6f16f998eee7d90c3b] State change from Init -> WaitInitial client | 0.001 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.001 DEBUG Building Initial dcid Some(CID [9]: 6f16f998eee7d90c3b) scid Some(CID [0]: ) client | 0.001 DEBUG CRYPTO for in offset=0, len=1212 client | 0.002 DEBUG [Client 6f16f998eee7d90c3b] pn=0 type=Initial pri-path:6f16f998eee7d90c3b 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1212 } client | 0.002 DEBUG packet_sent this=0x55f690763630, pn=0, ps=1252 client | 0.002 DEBUG ECN probing: sent 1 probes client | 0.002 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 6f16f998eee7d90c3b) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1212, len=327 client | 0.002 DEBUG [Client 6f16f998eee7d90c3b] pn=1 type=Initial pri-path:6f16f998eee7d90c3b 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 368 client | TX -> Crypto { offset: 1212, len: 327 } client | 0.002 DEBUG [Client 6f16f998eee7d90c3b] pad Initial from 368 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55f690763630, pn=1, ps=1252 client | 0.002 DEBUG ECN probing: sent 2 probes client | 0.002 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 6f16f998eee7d90c3b) 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 6f16f998eee7d90c3b] delay duration 3.88166ms client | 0.002 DEBUG Setting timeout of 3.88166ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 6f16f998eee7d90c3b) 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 6f16f998eee7d90c3b] delay duration 3.795049ms client | 0.002 DEBUG Setting timeout of 3.795049ms client | 0.007 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [9]: 6f16f998eee7d90c3b) 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 6f16f998eee7d90c3b] delay duration 294.718184ms client | 0.007 DEBUG Setting timeout of 294.718184ms client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] pn=9896948 type=Initial pri-path:6f16f998eee7d90c3b 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Ack { largest_acknowledged: 1, ack_delay: 86, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Padding { len: 1094 } client | 0.040 DEBUG Read Ok(90) bytes client | 0.040 DEBUG Write secret available for Handshake: SymKey [32]: 6686a0ee9350a109a9030f8a050b5751cf46ae4ccfd08cb70550f32a0ecf1a7b client | 0.040 DEBUG Read secret available for Handshake: SymKey [32]: 208a090b300e7f8a9f3ce084b03b83e0580b51eeccb248d518a8822104e5006a client | 0.040 DEBUG [Agent 0x55f69074ff20] state -> InProgress client | 0.040 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 0.040 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 0.040 DEBUG [Crypto] Handshake keys installed client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] Rx ACK space=in, ranges=[0..=1] client | 0.040 DEBUG [LossRecovery] ACK for Initial - largest_acked=1 client | 0.040 DEBUG on_packets_acked this=0x55f690763630, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=2504 client | 0.040 DEBUG Acked crypto frame space=in offset=1212 length=327 client | 0.040 DEBUG Acked crypto frame space=in offset=0 length=1212 client | 0.040 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 555032774 } client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] Changing to use Server CID=4000001ec9affc08 client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] State change from WaitInitial -> Handshaking client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG Building Initial dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] pn=2 type=Initial pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 44 client | TX -> Ack { largest_acknowledged: 9896948, ack_delay: 69, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.040 DEBUG Building Handshake dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] pad Initial from 44 to PLPMTU 1252 client | 0.040 DEBUG ECN probing: sent 3 probes client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG Building Initial dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.040 DEBUG Building Handshake dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.040 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] delay duration 75.756558ms client | 0.040 DEBUG Setting timeout of 75.756558ms client | 0.040 DEBUG [Client 6f16f998eee7d90c3b] pn=9896948 type=Handshake pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 755 client | -> RX Crypto { offset: 0, len: 714 } client | 0.040 DEBUG Read Ok(714) bytes client | 0.041 DEBUG [Agent 0x55f69074ff20] state -> AuthenticationPending client | 0.041 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 555675265 } client | 0.041 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.041 DEBUG Building Initial dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.041 DEBUG Building Handshake dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.041 DEBUG [Client 6f16f998eee7d90c3b] pn=0 type=Handshake pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 9896948, ack_delay: 84, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.041 DEBUG [Client 6f16f998eee7d90c3b] Drop packet number space in client | 0.041 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 0.041 DEBUG ECN probing: sent 4 probes client | 0.041 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.041 DEBUG Building Handshake dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.041 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.041 DEBUG [Client 6f16f998eee7d90c3b] delay duration 114.467671ms client | 0.041 DEBUG Setting timeout of 114.467671ms client | 0.041 DEBUG StateChange(Handshaking) client | 0.041 WARN Cannot create stream ConnectionState client | 0.041 DEBUG [Client 6f16f998eee7d90c3b] Authenticated Ok client | 0.041 DEBUG Write secret available for ApplicationData: SymKey [32]: 379e4857e84d202cb5c5e8227ebd0a75b51d3f3621892e84aeca617690474dd2 client | 0.041 DEBUG Read secret available for ApplicationData: SymKey [32]: b5d1a4bcc4c25f3c94837b699186d093148704c4218a3740ac14190a1b049c0b client | 0.041 DEBUG [Agent 0x55f69074ff20] SSL_AuthCertificateComplete: Ok(()) client | 0.041 DEBUG [Agent 0x55f69074ff20] 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.041 DEBUG [Client 6f16f998eee7d90c3b] TLS connection complete client | 0.041 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 0.041 DEBUG [Crypto] Application write key installed client | 0.041 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 0.041 DEBUG [Crypto] application read keys installed client | 0.041 DEBUG [Client 6f16f998eee7d90c3b] State change from Handshaking -> Connected client | 0.041 INFO [Client 6f16f998eee7d90c3b] Connection established client | 0.041 DEBUG stream BiDi creatable client | 0.041 INFO Created stream 0 for https://server4/tfdhyesrfz client | 0.041 INFO Saving https://server4/tfdhyesrfz to "/downloads/tfdhyesrfz" client | 0.042 DEBUG stream UniDi creatable client | 0.042 DEBUG StateChange(Connected) client | 0.042 DEBUG stream 0 writable client | 0.042 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.042 DEBUG Building Handshake dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.042 DEBUG CRYPTO for hs offset=0, len=36 client | 0.042 DEBUG [Client 6f16f998eee7d90c3b] pn=1 type=Handshake pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 0.042 DEBUG packet_sent this=0x55f690763630, pn=1, ps=73 client | 0.042 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.042 DEBUG [Client 6f16f998eee7d90c3b] pn=0 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 119 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.042 DEBUG packet_sent this=0x55f690763630, pn=0, ps=46 client | 0.042 DEBUG ECN probing: sent 5 probes client | 0.042 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.042 DEBUG Building Handshake dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.042 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.042 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.042 DEBUG [Client 6f16f998eee7d90c3b] delay duration 114.476615ms client | 0.042 DEBUG Setting timeout of 114.476615ms client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] pn=9896949 type=Handshake pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 38 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 49, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] Rx ACK space=hs, ranges=[0..=1] client | 0.073 DEBUG [LossRecovery] ACK for Handshake - largest_acked=1 client | 0.073 DEBUG on_packets_acked this=0x55f690763630, limited=1, bytes_in_flight=46, cwnd=12520, state=SlowStart, new_acked=73 client | 0.073 DEBUG Acked crypto frame space=hs offset=0 length=36 client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG Building Handshake dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.073 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.073 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] delay duration 70.863414ms client | 0.073 DEBUG Setting timeout of 70.863414ms client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG Building Handshake dcid Some(CID [8]: 4000001ec9affc08) scid Some(CID [0]: ) client | 0.073 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.073 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] delay duration 70.839579ms client | 0.073 DEBUG Setting timeout of 70.839579ms client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] pn=9896948 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 400 client | -> RX Crypto { offset: 0, len: 250 } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 55, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [64, 0, 0, 23, 206, 4, 39, 29], stateless_reset_token: [168, 124, 159, 97, 129, 144, 52, 214, 133, 189, 27, 34, 151, 129, 163, 177] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [64, 0, 0, 17, 161, 16, 199, 129], stateless_reset_token: [145, 133, 252, 57, 171, 229, 240, 96, 119, 207, 234, 85, 97, 204, 133, 152] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [64, 0, 0, 2, 137, 203, 218, 193], stateless_reset_token: [238, 89, 90, 230, 54, 120, 34, 65, 8, 240, 76, 177, 196, 182, 95, 11] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [64, 0, 0, 31, 41, 166, 82, 161], stateless_reset_token: [186, 181, 89, 190, 184, 86, 163, 47, 119, 189, 58, 133, 203, 166, 52, 125] } client | -> RX Padding { len: 7 } client | 0.073 DEBUG Read Ok(250) bytes client | 0.073 DEBUG [0x55f69074ff20] Got resumption token [921]: 02000633fae93e0f..0bc2fc46d5279f5a client | 0.073 DEBUG [Agent 0x55f69074ff20] 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.073 DEBUG setting max_stream_data to 107374182 client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] Rx ACK space=ap, ranges=[0..=0] client | 0.073 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 client | 0.073 DEBUG on_packets_acked this=0x55f690763630, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=46 client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] State change from Connected -> Confirmed client | 0.073 DEBUG PMTUD started with probe size 1380 client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] Drop packet number space hs client | 0.073 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] No preferred address to migrate to client | 0.073 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 588114907 } client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.073 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] pn=1 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352 client | TX -> Ack { largest_acknowledged: 9896948, ack_delay: 27, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> Ping client | TX -> Padding { len: 1317 } client | 0.073 DEBUG packet_sent this=0x55f690763630, pn=1, ps=1352 client | 0.073 DEBUG ECN probing: sent 6 probes client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.073 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] delay duration 115.845ms client | 0.073 DEBUG Setting timeout of 115.845ms client | 0.073 DEBUG stream BiDi creatable client | 0.073 DEBUG stream UniDi creatable client | 0.073 DEBUG stream 0 complete client | 0.073 WARN Unhandled event StateChange(Confirmed) client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.073 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG [Client 6f16f998eee7d90c3b] delay duration 115.812559ms client | 0.073 DEBUG Setting timeout of 115.812559ms client | 0.074 DEBUG [Client 6f16f998eee7d90c3b] pn=9896949 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 0, len: >>1209, fin: false } client | 0.074 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 609092002 } client | 0.074 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG [Client 6f16f998eee7d90c3b] delay duration 19.97312ms client | 0.074 DEBUG Setting timeout of 19.97312ms client | 0.074 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG [Client 6f16f998eee7d90c3b] delay duration 19.945057ms client | 0.074 DEBUG Setting timeout of 19.945057ms client | 0.075 DEBUG [Client 6f16f998eee7d90c3b] pn=9896950 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 1209, len: >>1207, fin: false } client | 0.075 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 590119763 } client | 0.075 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.075 DEBUG [Client 6f16f998eee7d90c3b] pn=2 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 34 client | TX -> Ack { largest_acknowledged: 9896950, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 0.075 DEBUG ECN probing: sent 7 probes client | 0.075 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 6f16f998eee7d90c3b] delay duration 114.08017ms client | 0.075 DEBUG Setting timeout of 114.08017ms client | 0.075 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 6f16f998eee7d90c3b] delay duration 114.061174ms client | 0.075 DEBUG Setting timeout of 114.061174ms client | 0.076 DEBUG [Client 6f16f998eee7d90c3b] pn=9896951 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 2416, len: >>1207, fin: false } client | 0.076 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 611131645 } client | 0.076 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG [Client 6f16f998eee7d90c3b] delay duration 19.975203ms client | 0.076 DEBUG Setting timeout of 19.975203ms client | 0.076 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG [Client 6f16f998eee7d90c3b] delay duration 19.95217ms client | 0.076 DEBUG Setting timeout of 19.95217ms client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] pn=9896952 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 3623, len: >>1207, fin: false } client | 0.077 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 592141313 } client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] pn=3 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 34 client | TX -> Ack { largest_acknowledged: 9896952, ack_delay: 3, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 0.077 DEBUG ECN probing: sent 8 probes client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] delay duration 112.04779ms client | 0.077 DEBUG Setting timeout of 112.04779ms client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] delay duration 112.023885ms client | 0.077 DEBUG Setting timeout of 112.023885ms client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] pn=9896953 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 336 client | -> RX Stream { stream_id: 0, offset: 4830, len: 290, fin: true } client | -> RX Padding { len: 19 } client | 0.077 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 612424021 } client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] delay duration 19.971055ms client | 0.077 DEBUG Setting timeout of 19.971055ms client | 0.077 DEBUG resumption token [1019]: 0000000124405a00..0bc2fc46d5279f5a client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] delay duration 19.896877ms client | 0.077 DEBUG Setting timeout of 19.896877ms client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 272, tv_nsec: 940366332 } } client | 0.077 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 272, tv_nsec: 940366332 } }) client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.077 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 272, tv_nsec: 592552722 } client | 0.077 DEBUG [Client 6f16f998eee7d90c3b] pn=4 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 45 client | TX -> Ack { largest_acknowledged: 9896953, ack_delay: 16, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.077 DEBUG ECN probing: sent 9 probes client | 0.077 DEBUG Setting timeout of 347.742595ms server | I20250430 08:43:28.282716 33 H1QDownstreamSession.h:84] onConnectionEnd client | 0.108 DEBUG [Client 6f16f998eee7d90c3b] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [8]: 4000001ec9affc08) client | 0.108 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 272, tv_nsec: 623089136 } client | 0.108 DEBUG [Client 6f16f998eee7d90c3b] pn=5 type=Short pri-path:4000001ec9affc08 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 46 client | TX -> Ack { largest_acknowledged: 9896953, ack_delay: 3833, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.108 DEBUG ECN probing: sent 10 probes client | 0.108 DEBUG ECN probing concluded with 10 probes sent client | 0.108 DEBUG Setting timeout of 317.160057ms client | 0.108 DEBUG Setting timeout of 317.147574ms client | 0.425 DEBUG [Client 6f16f998eee7d90c3b] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 272, tv_nsec: 940366332 } } -> Closed(Application(0)) client | 0.425 INFO Closing timer expired client | 0.425 DEBUG Output::None client | 0.425 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.425 DEBUG Timer fired while closed client | 0.425 DEBUG Output::None client | 0.426 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=b82c84d1bc2ea849e33252 client | 0.426 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 0.426 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 client | 0.426 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 0.426 DEBUG [unv-path 0.0.0.0:44822->193.167.100.100:443] Make permanent client | 0.426 DEBUG [unv-path:b82c84d1bc2ea849e33252 0.0.0.0:44822->193.167.100.100:443] set as primary path client | 0.426 DEBUG [pri-unv-path:b82c84d1bc2ea849e33252 0.0.0.0:44822->193.167.100.100:443] Path validated Instant { tv_sec: 272, tv_nsec: 940893287 } client | 0.426 INFO [Client b82c84d1bc2ea849e33252] resumption token [1019]: 0000000124405a00..0bc2fc46d5279f5a client | 0.426 DEBUG Overwrite initial version Version1 ==> Version1 client | 0.426 DEBUG Overwrite initial version Version1 ==> Version1 client | 0.426 DEBUG [Client b82c84d1bc2ea849e33252] client_start client | 0.426 DEBUG Writing transport parameters, msg=1 client | 0.426 DEBUG Write secret available for ZeroRtt: SymKey [32]: 8ed0a148b08f2121a5fde2b560ac4b71d23b4ce5040c493e0d17b5a756fa7ab7 client | 0.426 DEBUG [Agent 0x55f690793300] state -> InProgress client | 0.426 DEBUG [Client b82c84d1bc2ea849e33252] State change from Init -> WaitInitial client | 0.426 DEBUG Making Write ZeroRtt CryptoDxState, v=Version1 cipher=4865 client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] Enabled 0-RTT client | 0.427 DEBUG stream BiDi creatable client | 0.427 INFO Created stream 0 for https://server4/axuayaqjgf client | 0.427 INFO Saving https://server4/axuayaqjgf to "/downloads/axuayaqjgf" client | 0.427 DEBUG stream UniDi creatable client | 0.427 DEBUG StateChange(WaitInitial) client | 0.427 DEBUG stream 0 writable client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.427 DEBUG Building Initial dcid Some(CID [11]: b82c84d1bc2ea849e33252) scid Some(CID [0]: ) client | 0.427 DEBUG CRYPTO for in offset=0, len=1210 client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] pn=0 type=Initial pri-path:b82c84d1bc2ea849e33252 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1210 } client | 0.427 DEBUG packet_sent this=0x55f690763630, pn=0, ps=1252 client | 0.427 DEBUG ECN probing: sent 1 probes client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.427 DEBUG Building Initial dcid Some(CID [11]: b82c84d1bc2ea849e33252) scid Some(CID [0]: ) client | 0.427 DEBUG CRYPTO for in offset=1210, len=605 client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] pn=1 type=Initial pri-path:b82c84d1bc2ea849e33252 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 648 client | TX -> Crypto { offset: 1210, len: 605 } client | 0.427 DEBUG Building ZeroRtt dcid Some(CID [11]: b82c84d1bc2ea849e33252) scid Some(CID [0]: ) client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] pn=0 type=ZeroRtt pri-path:b82c84d1bc2ea849e33252 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 705 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.427 DEBUG packet_sent this=0x55f690763630, pn=0, ps=57 client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] pad Initial from 705 to PLPMTU 1252 client | 0.427 DEBUG packet_sent this=0x55f690763630, pn=1, ps=1195 client | 0.427 DEBUG ECN probing: sent 2 probes client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.427 DEBUG Building Initial dcid Some(CID [11]: b82c84d1bc2ea849e33252) scid Some(CID [0]: ) client | 0.427 DEBUG Building ZeroRtt dcid Some(CID [11]: b82c84d1bc2ea849e33252) scid Some(CID [0]: ) client | 0.427 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.427 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 107.849869ms client | 0.427 DEBUG Setting timeout of 107.849869ms client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113878 type=Initial pri-path:b82c84d1bc2ea849e33252 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 0, len: 96 } client | -> RX Ack { largest_acknowledged: 1, ack_delay: 47, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Padding { len: 1090 } client | 0.461 DEBUG Read Ok(96) bytes client | 0.461 DEBUG Write secret available for Handshake: SymKey [32]: ccfdb563eaa07256b0ab2b733165a8061e468a3c5ca2d0b66fdf5312a1c1705d client | 0.461 DEBUG Read secret available for Handshake: SymKey [32]: 6d634f3416879e75498a74c97ec1368b2d33c365dbf10ccaae07c2fbad11cdb1 client | 0.461 DEBUG [Agent 0x55f690793300] state -> InProgress client | 0.461 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 0.461 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 0.461 DEBUG [Crypto] Handshake keys installed client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] Rx ACK space=in, ranges=[0..=1] client | 0.461 DEBUG [LossRecovery] ACK for Initial - largest_acked=1 client | 0.461 DEBUG on_packets_acked this=0x55f690763630, limited=1, bytes_in_flight=57, cwnd=12520, state=SlowStart, new_acked=2447 client | 0.461 DEBUG Acked crypto frame space=in offset=1210 length=605 client | 0.461 DEBUG Acked crypto frame space=in offset=0 length=1210 client | 0.461 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 975938884 } client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] Changing to use Server CID=400000126c75f119 client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] State change from WaitInitial -> Handshaking client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.461 DEBUG Building Initial dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] pn=2 type=Initial pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 6113878, ack_delay: 60, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.461 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.461 DEBUG Building ZeroRtt dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] pad Initial from 43 to PLPMTU 1252 client | 0.461 DEBUG ECN probing: sent 3 probes client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.461 DEBUG Building Initial dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.461 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.461 DEBUG Building ZeroRtt dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.461 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 66.990747ms client | 0.461 DEBUG Setting timeout of 66.990747ms client | 0.461 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113878 type=Handshake pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 211 client | -> RX Crypto { offset: 0, len: 171 } client | 0.461 DEBUG Read Ok(171) bytes client | 0.461 DEBUG Write secret available for ApplicationData: SymKey [32]: c00fc3d53a92030a44acba7f22b6d525f92677878f36453ac06671905937db0d client | 0.461 DEBUG Read secret available for ApplicationData: SymKey [32]: b345d5d7a0700768026bd22b363efc7581cebb52dde1219128515713e86488fb client | 0.462 DEBUG [Agent 0x55f690793300] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: true, early_data: true, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] TLS connection complete client | 0.462 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 0.462 DEBUG [Crypto] Application write key installed client | 0.462 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 0.462 DEBUG [Crypto] application read keys installed client | 0.462 DEBUG setting max_stream_data to 107374182 client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] State change from Handshaking -> Connected client | 0.462 INFO [Client b82c84d1bc2ea849e33252] Connection established client | 0.462 DEBUG setting max_stream_data to 107374182 client | 0.462 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 976559945 } client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG Building Initial dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.462 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.462 DEBUG CRYPTO for hs offset=0, len=36 client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] pn=0 type=Handshake pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 81 client | TX -> Ack { largest_acknowledged: 6113878, ack_delay: 59, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 0.462 DEBUG packet_sent this=0x55f690763630, pn=0, ps=81 client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] Drop packet number space in client | 0.462 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 0.462 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.462 DEBUG ECN probing: sent 4 probes client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.462 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.462 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 101.211411ms client | 0.462 DEBUG Setting timeout of 101.211411ms client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113878 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 48 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 61, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX Padding { len: 23 } client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] Rx ACK space=ap, ranges=[0..=0] client | 0.462 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 client | 0.462 DEBUG on_packets_acked this=0x55f690763630, limited=1, bytes_in_flight=81, cwnd=12520, state=SlowStart, new_acked=57 client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.462 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.462 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 85.13411ms client | 0.462 DEBUG Setting timeout of 85.13411ms client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113879 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 0, len: >>1210, fin: false } client | 0.462 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 997277928 } client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.462 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.462 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.981817ms client | 0.462 DEBUG Setting timeout of 19.981817ms client | 0.462 DEBUG StateChange(Handshaking) client | 0.462 DEBUG stream BiDi creatable client | 0.462 DEBUG stream UniDi creatable client | 0.462 DEBUG StateChange(Connected) client | 0.462 DEBUG stream 0 complete client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.462 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.462 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.462 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.953023ms client | 0.462 DEBUG Setting timeout of 19.953023ms client | 0.463 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113880 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 1210, len: >>1208, fin: false } client | 0.463 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 978204152 } client | 0.463 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.463 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.463 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.463 DEBUG [Client b82c84d1bc2ea849e33252] pn=1 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 34 client | TX -> Ack { largest_acknowledged: 6113880, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 0.463 DEBUG ECN probing: sent 5 probes client | 0.463 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.463 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.463 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.463 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.463 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 84.096528ms client | 0.463 DEBUG Setting timeout of 84.096528ms client | 0.463 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.463 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.463 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.463 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.463 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 84.068957ms client | 0.463 DEBUG Setting timeout of 84.068957ms client | 0.464 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113881 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 2418, len: >>1208, fin: false } client | 0.464 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 999207858 } client | 0.464 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.464 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.464 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.464 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.464 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.974392ms client | 0.464 DEBUG Setting timeout of 19.974392ms client | 0.464 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.464 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.464 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.464 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.464 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.949926ms client | 0.464 DEBUG Setting timeout of 19.949926ms client | 0.465 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113882 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 3626, len: >>1208, fin: false } client | 0.465 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 980221413 } client | 0.465 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.465 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.465 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.465 DEBUG [Client b82c84d1bc2ea849e33252] pn=2 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 34 client | TX -> Ack { largest_acknowledged: 6113882, ack_delay: 3, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 0.465 DEBUG ECN probing: sent 6 probes client | 0.465 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.465 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.465 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.465 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.465 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 82.072914ms client | 0.465 DEBUG Setting timeout of 82.072914ms client | 0.465 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.465 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.465 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.465 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.465 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 82.03829ms client | 0.465 DEBUG Setting timeout of 82.03829ms client | 0.466 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113883 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 4834, len: >>1208, fin: false } client | 0.466 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 273, tv_nsec: 1232454 } client | 0.466 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.466 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.466 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.466 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.466 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.973611ms client | 0.466 DEBUG Setting timeout of 19.973611ms client | 0.466 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.466 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.466 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.466 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.466 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.949546ms client | 0.466 DEBUG Setting timeout of 19.949546ms client | 0.467 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113884 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 6042, len: >>1208, fin: false } client | 0.467 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 982241197 } client | 0.467 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.467 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.467 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.467 DEBUG [Client b82c84d1bc2ea849e33252] pn=3 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 34 client | TX -> Ack { largest_acknowledged: 6113884, ack_delay: 3, first_ack_range: 6, ack_ranges: [], ecn_count: None } client | 0.467 DEBUG ECN probing: sent 7 probes client | 0.467 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.467 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.467 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.467 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.467 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 80.035856ms client | 0.467 DEBUG Setting timeout of 80.035856ms client | 0.467 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.467 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.467 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.467 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.467 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 80.002884ms client | 0.467 DEBUG Setting timeout of 80.002884ms client | 0.468 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113885 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 7250, len: >>1208, fin: false } client | 0.468 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 273, tv_nsec: 3242029 } client | 0.468 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.468 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.468 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.468 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.468 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.976176ms client | 0.468 DEBUG Setting timeout of 19.976176ms client | 0.468 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.468 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.468 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.468 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.468 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.947112ms client | 0.468 DEBUG Setting timeout of 19.947112ms client | 0.469 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113886 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 8458, len: >>1208, fin: false } client | 0.469 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 272, tv_nsec: 984247278 } client | 0.469 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.469 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.469 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.469 DEBUG [Client b82c84d1bc2ea849e33252] pn=4 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 34 client | TX -> Ack { largest_acknowledged: 6113886, ack_delay: 2, first_ack_range: 8, ack_ranges: [], ecn_count: None } client | 0.469 DEBUG ECN probing: sent 8 probes client | 0.469 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.469 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.469 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.469 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.469 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 78.065744ms client | 0.469 DEBUG Setting timeout of 78.065744ms client | 0.469 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.469 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.469 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.469 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.469 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 78.038173ms client | 0.469 DEBUG Setting timeout of 78.038173ms client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] pn=6113887 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 624 client | -> RX Stream { stream_id: 0, offset: 9666, len: 574, fin: true } client | -> RX Padding { len: 24 } client | 0.470 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 273, tv_nsec: 4783941 } client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.470 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.470 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.470 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.975995ms client | 0.470 DEBUG Setting timeout of 19.975995ms client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.470 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.470 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.470 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] delay duration 19.924939ms client | 0.470 DEBUG Setting timeout of 19.924939ms client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] State change from Connected -> Closing { error: Application(0), timeout: Instant { tv_sec: 273, tv_nsec: 240929450 } } client | 0.470 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 273, tv_nsec: 240929450 } }) client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.470 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.470 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 272, tv_nsec: 984893677 } client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] pn=1 type=Handshake pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 47 client | TX -> Ack { largest_acknowledged: 6113878, ack_delay: 1041, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.470 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.470 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 272, tv_nsec: 984893677 } client | 0.470 DEBUG [Client b82c84d1bc2ea849e33252] pn=5 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 92 client | TX -> Ack { largest_acknowledged: 6113887, ack_delay: 13, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.470 DEBUG ECN probing: sent 9 probes client | 0.470 DEBUG Setting timeout of 255.887696ms server | I20250430 08:43:28.675184 33 H1QDownstreamSession.h:88] onConnectionError client | 0.493 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.493 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 273, tv_nsec: 8211163 } client | 0.493 DEBUG [Client b82c84d1bc2ea849e33252] pn=2 type=Handshake pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 47 client | TX -> Ack { largest_acknowledged: 6113878, ack_delay: 3956, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.493 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.493 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 273, tv_nsec: 8211163 } client | 0.493 DEBUG [Client b82c84d1bc2ea849e33252] pn=6 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, Ect0) len 93 client | TX -> Ack { largest_acknowledged: 6113887, ack_delay: 2928, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.493 DEBUG ECN probing: sent 10 probes client | 0.493 DEBUG ECN probing concluded with 10 probes sent client | 0.493 DEBUG Setting timeout of 232.627537ms client | 0.493 DEBUG Setting timeout of 232.618881ms client | 0.493 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.493 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 273, tv_nsec: 8578680 } client | 0.493 DEBUG [Client b82c84d1bc2ea849e33252] pn=3 type=Handshake pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 47 client | TX -> Ack { largest_acknowledged: 6113878, ack_delay: 4002, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.493 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.493 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 273, tv_nsec: 8578680 } client | 0.493 DEBUG [Client b82c84d1bc2ea849e33252] pn=7 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 93 client | TX -> Ack { largest_acknowledged: 6113887, ack_delay: 2974, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.493 DEBUG Setting timeout of 232.205608ms client | 0.493 DEBUG Setting timeout of 232.192534ms client | 0.500 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.500 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 273, tv_nsec: 15565564 } client | 0.500 DEBUG [Client b82c84d1bc2ea849e33252] pn=4 type=Handshake pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 47 client | TX -> Ack { largest_acknowledged: 6113878, ack_delay: 4875, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.500 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.500 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 273, tv_nsec: 15565564 } client | 0.500 DEBUG [Client b82c84d1bc2ea849e33252] pn=8 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 93 client | TX -> Ack { largest_acknowledged: 6113887, ack_delay: 3847, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.500 DEBUG Setting timeout of 225.252528ms client | 0.500 DEBUG [Client b82c84d1bc2ea849e33252] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG Building Handshake dcid Some(CID [8]: 400000126c75f119) scid Some(CID [0]: ) client | 0.500 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 273, tv_nsec: 15694535 } client | 0.500 DEBUG [Client b82c84d1bc2ea849e33252] pn=5 type=Handshake pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 47 client | TX -> Ack { largest_acknowledged: 6113878, ack_delay: 4891, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.500 DEBUG Building Short dcid Some(CID [8]: 400000126c75f119) client | 0.500 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 273, tv_nsec: 15694535 } client | 0.500 DEBUG [Client b82c84d1bc2ea849e33252] pn=9 type=Short pri-path:400000126c75f119 0.0.0.0:44822->193.167.100.100:443 IpTos(Cs0, NotEct) len 93 client | TX -> Ack { largest_acknowledged: 6113887, ack_delay: 3863, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.501 DEBUG Setting timeout of 225.143284ms client | 0.501 DEBUG Setting timeout of 225.132083ms client | 0.727 DEBUG [Client b82c84d1bc2ea849e33252] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 273, tv_nsec: 240929450 } } -> Closed(Application(0)) client | 0.727 INFO Closing timer expired client | 0.727 DEBUG Output::None client | 0.727 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.727 DEBUG Timer fired while closed client | 0.727 DEBUG Output::None client exited with code 0 Aborting on container exit... Container server Stopping Container client Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 08:43:39,344 Using the client's key log file. 2025-04-30 08:43:39,353 2025-04-30 08:43:39,353 Using the client's key log file. 2025-04-30 08:43:39,353 Using the client's key log file. 2025-04-30 08:43:39,361 2025-04-30 08:43:39,362 Using the client's key log file. 2025-04-30 08:43:39,362 Using the client's key log file. 2025-04-30 08:43:40,222 Check of downloaded files succeeded.