2025-09-12 05:46:13,709 Generated random file: xndxnsobyh of size: 10240 2025-09-12 05:46:13,709 Requests: https://server4:443/xndxnsobyh 2025-09-12 05:46:13,767 2025-09-12 05:46:13,767 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_xr0_gkth/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_7ogosa7y/ DOWNLOADS=/tmp/download_wkguiszd/ SERVER_LOGS=/tmp/logs_server_ndx4xhtk CLIENT_LOGS=/tmp/logs_client_ilbc2r6l SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=haproxytech/haproxy-qns:latest REQUESTS="https://server4:443/xndxnsobyh" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-12 05:46:26,858 Container server Recreate Container client Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 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 client | Endpoint's IPv4 address is 193.167.0.100 server | starting lighttpd server 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 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | haproxy version HAProxy version 0678d0a69b1a2a903781e33153630ae203a72aa7 2025/09/05 - https://haproxy.org/ server | Status: development branch - not safe for use in production. server | Known bugs: https://github.com/haproxy/haproxy/issues?q=is:issue+is:open server | Running on: Linux 6.11.0-1018-azure #18~24.04.1-Ubuntu SMP Sat Jun 28 04:46:03 UTC 2025 x86_64 server | starting haproxy... sim | server:443 is available after 501.582326ms sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: tcpdump: listening on eth1listening on eth0, link-type EN10MB (Ethernet), link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | , 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/xndxnsobyh ']' 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/xndxnsobyh client | ' client | ++ tee -i -a /logs/client.log client | 0.000 DEBUG Logging initialized client | 0.001 DEBUG Default socket send buffer size is Ok(212992) client | 0.001 DEBUG Default socket receive buffer size is 1048576, not changing client | 0.001 INFO hq-interop Client connecting: 0.0.0.0:49348 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=74eba23f04d174f2 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=32 client | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.001 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 0.001 DEBUG [unv-path 0.0.0.0:49348->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:74eba23f04d174f2 0.0.0.0:49348->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:74eba23f04d174f2 0.0.0.0:49348->193.167.100.100:443] Path validated Instant { tv_sec: 321, tv_nsec: 993814507 } client | 0.001 DEBUG [Client 74eba23f04d174f2] client_start client | 0.001 DEBUG Writing transport parameters, msg=1 client | 0.001 DEBUG [Agent 0x56046c3cc500] state -> InProgress client | 0.001 DEBUG [Client 74eba23f04d174f2] State change from Init -> WaitInitial client | 0.001 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.001 DEBUG Building Initial dcid Some(CID [8]: 74eba23f04d174f2) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1213 client | 0.002 DEBUG [Client 74eba23f04d174f2] pn=32 type=Initial pri-path:74eba23f04d174f2 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.002 DEBUG packet_sent this=0x56046c3dfaf0, pn=32, ps=1252 client | 0.002 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 74eba23f04d174f2) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=327 client | 0.002 DEBUG [Client 74eba23f04d174f2] pn=33 type=Initial pri-path:74eba23f04d174f2 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 367 client | TX -> Crypto { offset: 1213, len: 327 } client | 0.002 DEBUG [Client 74eba23f04d174f2] pad Initial from 367 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x56046c3dfaf0, pn=33, ps=1252 client | 0.002 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 74eba23f04d174f2) 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 74eba23f04d174f2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 74eba23f04d174f2) 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 74eba23f04d174f2] delay duration 3.93004ms client | 0.002 DEBUG Setting timeout of 3.93004ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 74eba23f04d174f2) 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 74eba23f04d174f2] delay duration 3.884214ms client | 0.002 DEBUG Setting timeout of 3.884214ms client | 0.007 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [8]: 74eba23f04d174f2) 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 74eba23f04d174f2] delay duration 294.249727ms client | 0.007 DEBUG Setting timeout of 294.249727ms client | 0.039 INFO [Client 74eba23f04d174f2] received Retry client | 0.039 INFO [Client 74eba23f04d174f2] Valid Retry received, token=9c3104bd4f5609ee41e5a532bc30b190e7006fd971075f001a1296c89873bd00fc2f45804eed81fa7cd2b6ea6640 scid=513f875e3a820ce7 client | 0.039 INFO [pri-path:513f875e3a820ce7 0.0.0.0:49348->193.167.100.100:443] discarding a packet without an RTT estimate; guessing RTT=37.375017ms client | 0.039 DEBUG [Client 74eba23f04d174f2] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.039 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.039 DEBUG [Client 74eba23f04d174f2] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 327 }) client | 0.039 INFO Lost crypto frame space=in offset=1213 length=327 client | 0.039 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=513f875e3a820ce7 client | 0.039 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.039 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.039 INFO [CryptoStates] Continue packet numbers for initial after retry (write is 0..0) client | 0.039 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: 513f875e3a820ce7) scid Some(CID [0]: ) client | 0.039 DEBUG CRYPTO for in offset=0, len=1167 client | 0.039 DEBUG [Client 74eba23f04d174f2] pn=34 type=Initial pri-path:513f875e3a820ce7 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1167 } client | 0.039 DEBUG packet_sent this=0x56046c3dfaf0, pn=34, ps=1252 client | 0.039 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: 513f875e3a820ce7) scid Some(CID [0]: ) client | 0.039 DEBUG CRYPTO for in offset=1167, len=373 client | 0.039 DEBUG [Client 74eba23f04d174f2] pn=35 type=Initial pri-path:513f875e3a820ce7 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 459 client | TX -> Crypto { offset: 1167, len: 373 } client | 0.039 DEBUG [Client 74eba23f04d174f2] pad Initial from 459 to PLPMTU 1252 client | 0.039 DEBUG packet_sent this=0x56046c3dfaf0, pn=35, ps=1252 client | 0.039 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: 513f875e3a820ce7) 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 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: 513f875e3a820ce7) 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 74eba23f04d174f2] delay duration 111.948558ms client | 0.039 DEBUG Setting timeout of 111.948558ms client | 0.039 INFO [Client 74eba23f04d174f2] received Retry client | 0.039 DEBUG [Client ...] Dropped received packet: Extra Retry; Total: 1 client | 0.039 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: 513f875e3a820ce7) 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 74eba23f04d174f2] delay duration 111.906359ms client | 0.039 DEBUG Setting timeout of 111.906359ms client | 0.039 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: 513f875e3a820ce7) 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 74eba23f04d174f2] delay duration 111.886131ms client | 0.039 DEBUG Setting timeout of 111.886131ms client | 0.074 DEBUG [Client 74eba23f04d174f2] pn=0 type=Initial pri-path:513f875e3a820ce7 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 134 client | -> RX Ack { largest_acknowledged: 35, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | 0.074 DEBUG [Client 74eba23f04d174f2] Rx ACK space=in, ranges=[34..=35] client | 0.074 DEBUG [recovery::Loss] ACK for Initial - largest_acked=35 client | 0.074 DEBUG on_packets_acked this=0x56046c3dfaf0, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=2504 client | 0.074 DEBUG Acked crypto frame space=in offset=1167 length=373 client | 0.074 DEBUG Acked crypto frame space=in offset=0 length=1167 client | 0.074 DEBUG Write secret available for Handshake: SymKey [48]: f0d331e83109d080bf0302727ee86baf6009d2dc97eabd4b6d2deb949b12662a46ca40105ea41051aeb1adc38b6ffec1 client | 0.074 DEBUG Read secret available for Handshake: SymKey [48]: 5573aaaecdccee011b2e6dd016ccf6d8aa8aff72ca2db83fef4b9842958e61c88250f212ea393c5919c5256e124af464 client | 0.074 DEBUG [Agent 0x56046c3cc500] state -> InProgress client | 0.074 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4866 min_pn=0 client | 0.074 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4866 min_pn=0 client | 0.074 DEBUG [Crypto] Handshake keys installed client | 0.074 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 67082777 } client | 0.074 DEBUG [Client 74eba23f04d174f2] Changing to use Server CID=36bd498208e86408 client | 0.074 DEBUG [Client 74eba23f04d174f2] State change from WaitInitial -> Handshaking client | 0.074 DEBUG [Client 74eba23f04d174f2] pn=0 type=Handshake pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1066 client | -> RX Crypto { offset: 0, len: 741 } client | -> RX Padding { len: 287 } client | 0.075 DEBUG [Agent 0x56046c3cc500] state -> AuthenticationPending client | 0.075 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 67082777 } client | 0.075 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Initial dcid Some(CID [8]: 36bd498208e86408) scid Some(CID [0]: ) client | 0.075 DEBUG [Client 74eba23f04d174f2] pn=36 type=Initial pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 87 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 151, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.075 DEBUG Building Handshake dcid Some(CID [8]: 36bd498208e86408) scid Some(CID [0]: ) client | 0.075 DEBUG [Client 74eba23f04d174f2] pn=0 type=Handshake pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 127 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 151, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.075 DEBUG [Client 74eba23f04d174f2] Drop packet number space in client | 0.075 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 0.075 INFO [recovery::Loss] ignoring in-36 from dropped space client | 0.075 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [8]: 36bd498208e86408) 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 74eba23f04d174f2] delay duration 95.336621ms client | 0.075 DEBUG Setting timeout of 95.336621ms client | 0.075 DEBUG StateChange(Handshaking) client | 0.075 WARN Cannot create stream ConnectionState client | 0.075 DEBUG [Client 74eba23f04d174f2] Authenticated Ok client | 0.075 DEBUG Write secret available for ApplicationData: SymKey [48]: f35149108635c6a02b3d81edb7ca0f90f519948db9eebde1d5639974d4bd400eb20f60f3765ecc10e8f0c601ea65d162 client | 0.075 DEBUG Read secret available for ApplicationData: SymKey [48]: fe90b57e4c6647fd341fe11359299ec2fe9eb269162583c343eabf50bef0b2fa921c8cef57a435d49cdff6e4d867a016 client | 0.075 DEBUG [Agent 0x56046c3cc500] SSL_AuthCertificateComplete: Ok(()) client | 0.075 DEBUG [Agent 0x56046c3cc500] 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 74eba23f04d174f2] TLS connection complete client | 0.075 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4866 min_pn=0 client | 0.075 DEBUG [Crypto] Application write key installed client | 0.075 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4866 min_pn=0 client | 0.075 DEBUG [Crypto] application read keys installed client | 0.076 DEBUG [Client 74eba23f04d174f2] State change from Handshaking -> Connected client | 0.076 INFO [Client 74eba23f04d174f2] Connection established client | 0.076 DEBUG stream BiDi creatable client | 0.076 INFO Created stream 0 for https://server4/xndxnsobyh client | 0.076 INFO Saving https://server4/xndxnsobyh to "/downloads/xndxnsobyh" client | 0.076 DEBUG stream UniDi creatable client | 0.076 DEBUG StateChange(Connected) client | 0.076 DEBUG stream 0 writable client | 0.076 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Handshake dcid Some(CID [8]: 36bd498208e86408) scid Some(CID [0]: ) client | 0.076 DEBUG CRYPTO for hs offset=0, len=52 client | 0.076 DEBUG [Client 74eba23f04d174f2] pn=1 type=Handshake pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 89 client | TX -> Crypto { offset: 0, len: 52 } client | 0.076 DEBUG packet_sent this=0x56046c3dfaf0, pn=1, ps=89 client | 0.076 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.076 DEBUG [Client 74eba23f04d174f2] pn=0 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 135 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.076 DEBUG packet_sent this=0x56046c3dfaf0, pn=0, ps=46 client | 0.076 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Handshake dcid Some(CID [8]: 36bd498208e86408) scid Some(CID [0]: ) client | 0.076 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG [Client 74eba23f04d174f2] delay duration 95.348323ms client | 0.076 DEBUG Setting timeout of 95.348323ms client | 0.108 DEBUG [Client 74eba23f04d174f2] pn=1 type=Handshake pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 38 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.108 DEBUG [Client 74eba23f04d174f2] Rx ACK space=hs, ranges=[0..=1] client | 0.108 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=1 client | 0.108 DEBUG on_packets_acked this=0x56046c3dfaf0, limited=1, bytes_in_flight=46, cwnd=12520, state=SlowStart, new_acked=89 client | 0.108 DEBUG Acked crypto frame space=hs offset=0 length=52 client | 0.108 DEBUG [Client 74eba23f04d174f2] pn=0 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 568 client | -> RX Crypto { offset: 0, len: 546 } client | 0.108 DEBUG [0x56046c3cc500] Got resumption token [936]: 0200063e942c1846..f4ba1252b8af0781 client | 0.108 DEBUG [0x56046c3cc500] Got resumption token [936]: 0200063e942c1846..7427a87082436c86 client | 0.108 DEBUG [Agent 0x56046c3cc500] 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 setting max_stream_data to 1474200 client | 0.108 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 120957917 } client | 0.108 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Handshake dcid Some(CID [8]: 36bd498208e86408) scid Some(CID [0]: ) client | 0.108 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 74eba23f04d174f2] delay duration 19.784255ms client | 0.108 DEBUG Setting timeout of 19.784255ms client | 0.108 DEBUG stream BiDi creatable client | 0.108 DEBUG stream UniDi creatable client | 0.108 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Handshake dcid Some(CID [8]: 36bd498208e86408) scid Some(CID [0]: ) client | 0.108 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 74eba23f04d174f2] delay duration 19.755891ms client | 0.108 DEBUG Setting timeout of 19.755891ms client | 0.109 DEBUG [Client 74eba23f04d174f2] pn=1 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 148 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 250, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX HandshakeDone client | -> RX NewToken { token: [183, 147, 201, 133, 15, 8, 47, 155, 13, 117, 184, 135, 166, 142, 200, 141, 172, 85, 42, 213, 50, 224, 173, 80, 169, 235, 18, 249, 137, 251, 89, 134, 238, 76, 41, 187, 113] } client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [130, 202, 98, 18, 119, 30, 110, 212], stateless_reset_token: [75, 51, 49, 190, 21, 223, 187, 161, 6, 23, 65, 59, 152, 129, 101, 105] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [184, 224, 138, 15, 72, 202, 171, 151], stateless_reset_token: [118, 41, 120, 181, 212, 151, 42, 1, 230, 204, 131, 75, 65, 190, 33, 79] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [96, 240, 234, 239, 146, 91, 93, 221], stateless_reset_token: [113, 66, 80, 208, 181, 72, 215, 9, 26, 171, 106, 17, 122, 181, 212, 115] } client | 0.109 DEBUG [Client 74eba23f04d174f2] Rx ACK space=ap, ranges=[0..=0] client | 0.109 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=0 client | 0.109 DEBUG on_packets_acked this=0x56046c3dfaf0, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=46 client | 0.109 DEBUG [Client 74eba23f04d174f2] State change from Connected -> Confirmed client | 0.109 DEBUG PMTUD started with probe size 1380 client | 0.109 DEBUG [Client 74eba23f04d174f2] Drop packet number space hs client | 0.109 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 0.109 DEBUG [Client 74eba23f04d174f2] No preferred address to migrate to client | 0.109 DEBUG resumption token [1089]: 0000000124406c00..7427a87082436c86 client | 0.109 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 101922237 } client | 0.109 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.109 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.109 DEBUG [Client 74eba23f04d174f2] pn=1 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 13, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> Ping client | TX -> Padding { len: 1320 } client | 0.109 DEBUG packet_sent this=0x56046c3dfaf0, pn=1, ps=1352 client | 0.109 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG ECN probing: sent 1 probes client | 0.109 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 74eba23f04d174f2] delay duration 100.890107ms client | 0.109 DEBUG Setting timeout of 100.890107ms client | 0.109 DEBUG stream 0 complete client | 0.109 WARN Unhandled event StateChange(Confirmed) client | 0.109 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 74eba23f04d174f2] delay duration 100.86499ms client | 0.109 DEBUG Setting timeout of 100.86499ms client | 0.111 DEBUG [Client 74eba23f04d174f2] pn=2 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 0, len: 1230, fin: false } client | 0.111 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 124233645 } client | 0.111 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG [Client 74eba23f04d174f2] delay duration 19.972518ms client | 0.111 DEBUG Setting timeout of 19.972518ms client | 0.111 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG [Client 74eba23f04d174f2] delay duration 19.951378ms client | 0.111 DEBUG Setting timeout of 19.951378ms client | 0.112 DEBUG [Client 74eba23f04d174f2] pn=3 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 1230, len: 1228, fin: false } client | 0.112 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 105264871 } client | 0.112 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.112 DEBUG [Client 74eba23f04d174f2] pn=2 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 3, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 0.112 DEBUG ECN probing: sent 2 probes client | 0.112 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.112 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG [Client 74eba23f04d174f2] delay duration 97.647571ms client | 0.112 DEBUG Setting timeout of 97.647571ms client | 0.112 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.112 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG [Client 74eba23f04d174f2] delay duration 97.618557ms client | 0.112 DEBUG Setting timeout of 97.618557ms client | 0.117 DEBUG [Client 74eba23f04d174f2] pn=4 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 2458, len: 1228, fin: false } client | 0.117 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 129852779 } client | 0.117 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.117 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG [Client 74eba23f04d174f2] delay duration 19.973039ms client | 0.117 DEBUG Setting timeout of 19.973039ms client | 0.117 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.117 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG [Client 74eba23f04d174f2] delay duration 19.952651ms client | 0.117 DEBUG Setting timeout of 19.952651ms client | 0.118 DEBUG [Client 74eba23f04d174f2] pn=5 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 3686, len: 1228, fin: false } client | 0.118 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 110883704 } client | 0.118 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.118 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.118 DEBUG [Client 74eba23f04d174f2] pn=3 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 0.118 DEBUG ECN probing: sent 3 probes client | 0.118 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.118 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.118 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.118 DEBUG [Client 74eba23f04d174f2] delay duration 92.010952ms client | 0.118 DEBUG Setting timeout of 92.010952ms client | 0.118 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.118 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.118 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.118 DEBUG [Client 74eba23f04d174f2] delay duration 91.969094ms client | 0.118 DEBUG Setting timeout of 91.969094ms client | 0.122 DEBUG [Client 74eba23f04d174f2] pn=6 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 4914, len: 1228, fin: false } client | 0.122 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 135511863 } client | 0.122 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.122 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.122 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.122 DEBUG [Client 74eba23f04d174f2] delay duration 19.974352ms client | 0.122 DEBUG Setting timeout of 19.974352ms client | 0.122 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.122 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.122 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.122 DEBUG [Client 74eba23f04d174f2] delay duration 19.954385ms client | 0.122 DEBUG Setting timeout of 19.954385ms client | 0.123 DEBUG [Client 74eba23f04d174f2] pn=7 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 6142, len: 1228, fin: false } client | 0.123 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 116528428 } client | 0.123 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.123 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.123 DEBUG [Client 74eba23f04d174f2] pn=4 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 6, first_ack_range: 7, ack_ranges: [], ecn_count: None } client | 0.123 DEBUG ECN probing: sent 4 probes client | 0.123 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.123 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.123 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.123 DEBUG [Client 74eba23f04d174f2] delay duration 86.355631ms client | 0.123 DEBUG Setting timeout of 86.355631ms client | 0.123 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.123 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.123 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.123 DEBUG [Client 74eba23f04d174f2] delay duration 86.332026ms client | 0.123 DEBUG Setting timeout of 86.332026ms client | 0.127 DEBUG [Client 74eba23f04d174f2] pn=8 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 7370, len: 1228, fin: false } client | 0.127 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 140120981 } client | 0.127 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.127 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.127 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.127 DEBUG [Client 74eba23f04d174f2] delay duration 19.972769ms client | 0.127 DEBUG Setting timeout of 19.972769ms client | 0.127 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.127 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.127 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.127 DEBUG [Client 74eba23f04d174f2] delay duration 19.953182ms client | 0.127 DEBUG Setting timeout of 19.953182ms client | 0.128 DEBUG [Client 74eba23f04d174f2] pn=9 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 8598, len: 1228, fin: false } client | 0.128 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 121149541 } client | 0.128 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.128 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.128 DEBUG [Client 74eba23f04d174f2] pn=5 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 3, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | 0.128 DEBUG ECN probing: sent 5 probes client | 0.128 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.128 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.128 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.128 DEBUG [Client 74eba23f04d174f2] delay duration 81.761147ms client | 0.128 DEBUG Setting timeout of 81.761147ms client | 0.128 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.128 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.128 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.128 DEBUG [Client 74eba23f04d174f2] delay duration 81.733175ms client | 0.128 DEBUG Setting timeout of 81.733175ms client | 0.131 DEBUG [Client 74eba23f04d174f2] pn=10 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, NotEct) len 438 client | -> RX Stream { stream_id: 0, offset: 9826, len: 414, fin: true } client | 0.131 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 322, tv_nsec: 144023004 } client | 0.131 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.131 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.131 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.131 DEBUG [Client 74eba23f04d174f2] delay duration 19.968601ms client | 0.131 DEBUG Setting timeout of 19.968601ms client | 0.131 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.131 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.131 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.131 DEBUG [Client 74eba23f04d174f2] delay duration 19.919709ms client | 0.131 DEBUG Setting timeout of 19.919709ms client | 0.131 DEBUG [Client 74eba23f04d174f2] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 322, tv_nsec: 426997797 } } client | 0.131 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 322, tv_nsec: 426997797 } }) client | 0.131 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.131 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.131 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 322, tv_nsec: 124133832 } client | 0.131 DEBUG [Client 74eba23f04d174f2] pn=6 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, Ect0) len 42 client | TX -> Ack { largest_acknowledged: 10, ack_delay: 13, first_ack_range: 10, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.131 DEBUG ECN probing: sent 6 probes client | 0.131 DEBUG Setting timeout of 302.807188ms client | 0.162 DEBUG [Client 74eba23f04d174f2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.162 DEBUG Building Short dcid Some(CID [8]: 36bd498208e86408) client | 0.162 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 322, tv_nsec: 155140078 } client | 0.162 DEBUG [Client 74eba23f04d174f2] pn=7 type=Short pri-path:36bd498208e86408 0.0.0.0:49348->193.167.100.100:443 Tos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 10, ack_delay: 3889, first_ack_range: 10, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.162 DEBUG ECN probing: sent 7 probes client | 0.162 DEBUG Setting timeout of 271.730102ms client | 0.162 DEBUG Setting timeout of 271.7183ms client | 0.435 DEBUG [Client 74eba23f04d174f2] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 322, tv_nsec: 426997797 } } -> Closed(Application(0)) client | 0.435 INFO Closing timer expired client | 0.435 DEBUG Output::None client | 0.435 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.435 DEBUG Timer fired while closed client | 0.435 DEBUG Output::None client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-09-12 05:46:26,941 Using the client's key log file. 2025-09-12 05:46:26,950 2025-09-12 05:46:26,950 Using the client's key log file. 2025-09-12 05:46:26,950 Using the client's key log file. 2025-09-12 05:46:26,958 2025-09-12 05:46:26,959 Using the client's key log file. 2025-09-12 05:46:27,382 Check of downloaded files succeeded. 2025-09-12 05:46:27,818 Check of Retry succeeded. Token used: 9c3104bd4f5609ee41e5a532bc30b190e7006fd971075f001a1296c89873bd00fc2f45804eed81fa7cd2b6ea6640