2025-09-11 19:09:07,174 Generated random file: khibedvnxa of size: 5120 2025-09-11 19:09:07,174 Requests: https://server4:443/khibedvnxa 2025-09-11 19:09:07,549 2025-09-11 19:09:07,550 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_2tvixmjz/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_tq7jnlyy/ DOWNLOADS=/tmp/download_hxyuxt8i/ SERVER_LOGS=/tmp/logs_server_bp0k9br7 CLIENT_LOGS=/tmp/logs_client_kavxd_63 SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=haproxytech/haproxy-qns:latest REQUESTS="https://server4:443/khibedvnxa" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-11 19:09:27,238 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... server | Actual changes: server | tx-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 | starting lighttpd server client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 client | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + '[' -n transfer ']' 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.202742ms sim | Using scenario: droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7 sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads) client | + '[' https://server4:443/khibedvnxa ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test transfer --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/khibedvnxa 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:57833 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=a3cd494a014b0a9809 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=24 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:57833->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:a3cd494a014b0a9809 0.0.0.0:57833->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:a3cd494a014b0a9809 0.0.0.0:57833->193.167.100.100:443] Path validated Instant { tv_sec: 544, tv_nsec: 918762315 } client | 0.001 DEBUG [Client a3cd494a014b0a9809] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 0.002 DEBUG [Client a3cd494a014b0a9809] State change from Init -> WaitInitial client | 0.002 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: a3cd494a014b0a9809) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1212 client | 0.002 DEBUG [Client a3cd494a014b0a9809] pn=24 type=Initial pri-path:a3cd494a014b0a9809 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1212 } client | 0.002 DEBUG packet_sent this=0x55ebd350baf0, pn=24, ps=1252 client | 0.002 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: a3cd494a014b0a9809) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1212, len=328 client | 0.002 DEBUG [Client a3cd494a014b0a9809] pn=25 type=Initial pri-path:a3cd494a014b0a9809 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 369 client | TX -> Crypto { offset: 1212, len: 328 } client | 0.002 DEBUG [Client a3cd494a014b0a9809] pad Initial from 369 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55ebd350baf0, pn=25, ps=1252 client | 0.002 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: a3cd494a014b0a9809) 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 a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: a3cd494a014b0a9809) 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 a3cd494a014b0a9809] delay duration 3.89456ms client | 0.002 DEBUG Setting timeout of 3.89456ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: a3cd494a014b0a9809) 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 a3cd494a014b0a9809] delay duration 3.860196ms client | 0.002 DEBUG Setting timeout of 3.860196ms client | 0.007 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [9]: a3cd494a014b0a9809) 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 a3cd494a014b0a9809] delay duration 294.331865ms client | 0.007 DEBUG Setting timeout of 294.331865ms sim | Dropping packet 2 (1252 bytes) from 193.167.0.100 client | 0.040 DEBUG [Client a3cd494a014b0a9809] pn=0 type=Initial pri-path:a3cd494a014b0a9809 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 40 client | -> RX Ack { largest_acknowledged: 24, ack_delay: 1000, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.040 DEBUG [Client a3cd494a014b0a9809] Rx ACK space=in, ranges=[24..=24] client | 0.040 DEBUG [recovery::Loss] ACK for Initial - largest_acked=24 client | 0.040 DEBUG on_packets_acked this=0x55ebd350baf0, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252 client | 0.040 DEBUG Acked crypto frame space=in offset=0 length=1212 client | 0.040 DEBUG [Client a3cd494a014b0a9809] Changing to use Server CID=6a3d47bebf0d390e client | 0.040 DEBUG [Client a3cd494a014b0a9809] State change from WaitInitial -> WaitVersion client | 0.040 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) 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 a3cd494a014b0a9809] delay duration 77.436103ms client | 0.040 DEBUG Setting timeout of 77.436103ms client | 0.040 WARN Unhandled event StateChange(WaitVersion) client | 0.040 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) 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 a3cd494a014b0a9809] delay duration 77.390057ms client | 0.040 DEBUG Setting timeout of 77.390057ms client | 0.119 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.119 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 0.119 INFO Lost crypto frame space=in offset=1212 length=328 client | 0.119 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.119 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.119 DEBUG CRYPTO for in offset=1212, len=328 client | 0.119 DEBUG [Client a3cd494a014b0a9809] pn=26 type=Initial pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 368 client | TX -> Crypto { offset: 1212, len: 328 } client | 0.119 DEBUG [Client a3cd494a014b0a9809] pad Initial from 368 to PLPMTU 1252 client | 0.119 DEBUG packet_sent this=0x55ebd350baf0, pn=26, ps=1252 client | 0.119 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.119 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.119 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.120 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.120 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.120 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.120 DEBUG [Client a3cd494a014b0a9809] delay duration 232.428458ms client | 0.120 DEBUG Setting timeout of 232.428458ms sim | Dropping packet 3 (1252 bytes) from 193.167.0.100 client | 0.353 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.353 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 0.353 INFO Lost crypto frame space=in offset=1212 length=328 client | 0.353 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.353 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.353 DEBUG CRYPTO for in offset=1212, len=328 client | 0.353 DEBUG [Client a3cd494a014b0a9809] pn=27 type=Initial pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 368 client | TX -> Crypto { offset: 1212, len: 328 } client | 0.353 DEBUG [Client a3cd494a014b0a9809] pad Initial from 368 to PLPMTU 1252 client | 0.353 DEBUG packet_sent this=0x55ebd350baf0, pn=27, ps=1252 client | 0.353 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.353 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.353 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.353 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.353 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.353 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.353 DEBUG [Client a3cd494a014b0a9809] delay duration 465.015511ms client | 0.353 DEBUG Setting timeout of 465.015511ms sim | Dropping packet 4 (1252 bytes) from 193.167.0.100 client | 0.819 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.819 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 0.819 INFO Lost crypto frame space=in offset=1212 length=328 client | 0.819 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.819 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.819 DEBUG CRYPTO for in offset=1212, len=328 client | 0.819 DEBUG [Client a3cd494a014b0a9809] pn=28 type=Initial pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 368 client | TX -> Crypto { offset: 1212, len: 328 } client | 0.819 DEBUG [Client a3cd494a014b0a9809] pad Initial from 368 to PLPMTU 1252 client | 0.819 DEBUG packet_sent this=0x55ebd350baf0, pn=28, ps=1252 client | 0.819 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.819 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.819 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.819 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.819 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 0.819 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.819 DEBUG [Client a3cd494a014b0a9809] delay duration 930.257997ms client | 0.819 DEBUG Setting timeout of 930.257997ms sim | Dropping packet 5 (1252 bytes) from 193.167.0.100 client | 1.750 DEBUG [recovery::Loss] PTO timer fired for Initial client | 1.750 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 1.750 INFO Lost crypto frame space=in offset=1212 length=328 client | 1.750 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 1.750 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 1.750 DEBUG CRYPTO for in offset=1212, len=328 client | 1.750 DEBUG [Client a3cd494a014b0a9809] pn=29 type=Initial pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 368 client | TX -> Crypto { offset: 1212, len: 328 } client | 1.750 DEBUG [Client a3cd494a014b0a9809] pad Initial from 368 to PLPMTU 1252 client | 1.750 DEBUG packet_sent this=0x55ebd350baf0, pn=29, ps=1252 client | 1.750 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 1.750 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 1.750 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 1.750 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.750 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 1.750 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.750 DEBUG [Client a3cd494a014b0a9809] delay duration 1.860633794s client | 1.750 DEBUG Setting timeout of 1.860633794s sim | Dropping packet 6 (1252 bytes) from 193.167.0.100 client | 3.612 DEBUG [recovery::Loss] PTO timer fired for Initial client | 3.612 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 3.612 INFO Lost crypto frame space=in offset=1212 length=328 client | 3.612 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 3.612 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 3.612 DEBUG CRYPTO for in offset=1212, len=328 client | 3.612 DEBUG [Client a3cd494a014b0a9809] pn=30 type=Initial pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 368 client | TX -> Crypto { offset: 1212, len: 328 } client | 3.612 DEBUG [Client a3cd494a014b0a9809] pad Initial from 368 to PLPMTU 1252 client | 3.612 DEBUG packet_sent this=0x55ebd350baf0, pn=30, ps=1252 client | 3.612 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 3.612 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 3.612 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 3.612 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.612 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 3.612 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.612 DEBUG [Client a3cd494a014b0a9809] delay duration 3.721416307s client | 3.612 DEBUG Setting timeout of 3.721416307s sim | Dropping packet 7 (1252 bytes) from 193.167.0.100 client | 7.335 DEBUG [recovery::Loss] PTO timer fired for Initial client | 7.335 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 7.335 INFO Lost crypto frame space=in offset=1212 length=328 client | 7.335 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 7.335 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.335 DEBUG CRYPTO for in offset=1212, len=328 client | 7.335 DEBUG [Client a3cd494a014b0a9809] pn=31 type=Initial pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 368 client | TX -> Crypto { offset: 1212, len: 328 } client | 7.335 DEBUG [Client a3cd494a014b0a9809] pad Initial from 368 to PLPMTU 1252 client | 7.335 DEBUG packet_sent this=0x55ebd350baf0, pn=31, ps=1252 client | 7.335 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 7.335 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.335 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 7.336 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.336 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.336 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.336 DEBUG [Client a3cd494a014b0a9809] delay duration 7.443032639s client | 7.336 DEBUG Setting timeout of 7.443032639s client | 7.370 DEBUG [Client a3cd494a014b0a9809] pn=1 type=Initial pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 136 client | -> RX Ack { largest_acknowledged: 31, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 5, range: 0 }], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | 7.370 DEBUG [Client a3cd494a014b0a9809] Rx ACK space=in, ranges=[31..=31, 24..=24] client | 7.370 DEBUG [recovery::Loss] ACK for Initial - largest_acked=31 client | 7.370 DEBUG packet_lost this=0x55ebd350baf0, pn=25, ps=1252 client | 7.370 DEBUG packet_lost this=0x55ebd350baf0, pn=26, ps=1252 client | 7.370 DEBUG packet_lost this=0x55ebd350baf0, pn=27, ps=1252 client | 7.370 DEBUG packet_lost this=0x55ebd350baf0, pn=28, ps=1252 client | 7.370 DEBUG packet_lost this=0x55ebd350baf0, pn=29, ps=1252 client | 7.370 DEBUG packet_lost this=0x55ebd350baf0, pn=30, ps=1252 client | 7.370 INFO [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1252/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764 client | 7.370 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1252/8764 ssthresh 8764] state -> RecoveryStart client | 7.370 INFO [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1252/8764 ssthresh 8764] persistent congestion client | 7.370 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1252/2504 ssthresh 8764] state -> PersistentCongestion client | 7.370 DEBUG on_packets_lost this=0x55ebd350baf0, bytes_in_flight=1252, cwnd=2504, state=PersistentCongestion client | 7.370 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/2504 ssthresh 8764] slow start += 0 client | 7.370 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/2504 ssthresh 8764] on_packets_acked this=0x55ebd350baf0, limited=0, bytes_in_flight=0, cwnd=2504, state=PersistentCongestion, new_acked=0 client | 7.370 DEBUG Acked crypto frame space=in offset=1212 length=328 client | 7.370 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 7.370 INFO Lost crypto frame space=in offset=1212 length=328 client | 7.370 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 7.370 INFO Lost crypto frame space=in offset=1212 length=328 client | 7.370 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 7.370 INFO Lost crypto frame space=in offset=1212 length=328 client | 7.370 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 7.370 INFO Lost crypto frame space=in offset=1212 length=328 client | 7.370 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 7.370 INFO Lost crypto frame space=in offset=1212 length=328 client | 7.370 DEBUG [Client a3cd494a014b0a9809] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 328 }) client | 7.370 INFO Lost crypto frame space=in offset=1212 length=328 client | 7.371 DEBUG Write secret available for Handshake: SymKey [48]: b4d0f5044bbb249e4da80394d3f217a13e9a43b3ed45d5627aeb0229a71b63add8576586b1fcac4b3f664d451e65939e client | 7.371 DEBUG Read secret available for Handshake: SymKey [48]: f4fca0998232045fafd4f6dd266b79c93cb0f1ef1ef85ba29d5de9a18d7b37ebc2ade57a6dd48a9dcc5970fdbbb90716 client | 7.371 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.371 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4866 min_pn=0 client | 7.371 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4866 min_pn=0 client | 7.371 DEBUG [Crypto] Handshake keys installed client | 7.371 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 288472811 } client | 7.371 DEBUG [Client a3cd494a014b0a9809] State change from WaitVersion -> Handshaking client | 7.371 DEBUG [Client a3cd494a014b0a9809] pn=0 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1116 client | -> RX Crypto { offset: 0, len: 1078 } client | 7.371 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.371 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 288472811 } client | 7.371 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.371 DEBUG Building Initial dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.371 DEBUG [Client a3cd494a014b0a9809] pn=32 type=Initial pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 41 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 103, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 7.371 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.371 DEBUG [Client a3cd494a014b0a9809] pn=0 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 81 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 103, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 7.371 DEBUG [Client a3cd494a014b0a9809] Drop packet number space in client | 7.371 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 7.371 INFO [recovery::Loss] ignoring in-32 from dropped space client | 7.371 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.371 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.371 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.371 DEBUG [Client a3cd494a014b0a9809] delay duration 100.102465ms client | 7.371 DEBUG Setting timeout of 100.102465ms client | 7.371 DEBUG StateChange(Handshaking) client | 7.371 WARN Cannot create stream ConnectionState client | 7.371 DEBUG stream BiDi creatable client | 7.371 WARN Cannot create stream ConnectionState client | 7.371 DEBUG stream UniDi creatable client | 7.371 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.371 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.371 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.371 DEBUG [Client a3cd494a014b0a9809] delay duration 100.066037ms client | 7.371 DEBUG Setting timeout of 100.066037ms client | 7.371 DEBUG [Client a3cd494a014b0a9809] pn=1 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Crypto { offset: 1078, len: 1213 } client | 7.371 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.371 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 289565715 } client | 7.371 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.371 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.371 DEBUG [Client a3cd494a014b0a9809] pn=1 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 4, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 7.372 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.372 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.372 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.372 DEBUG [Client a3cd494a014b0a9809] delay duration 99.860263ms client | 7.372 DEBUG Setting timeout of 99.860263ms client | 7.372 DEBUG stream BiDi creatable client | 7.372 WARN Cannot create stream ConnectionState client | 7.372 DEBUG stream UniDi creatable client | 7.372 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.372 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.372 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.372 DEBUG [Client a3cd494a014b0a9809] delay duration 99.838171ms client | 7.372 DEBUG Setting timeout of 99.838171ms client | 7.372 DEBUG [Client a3cd494a014b0a9809] pn=2 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Crypto { offset: 2291, len: 1213 } client | 7.372 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.372 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 290498169 } client | 7.372 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.372 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.372 DEBUG [Client a3cd494a014b0a9809] pn=2 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 7.372 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.372 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.372 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.372 DEBUG [Client a3cd494a014b0a9809] delay duration 98.928059ms client | 7.372 DEBUG Setting timeout of 98.928059ms client | 7.372 DEBUG stream BiDi creatable client | 7.372 WARN Cannot create stream ConnectionState client | 7.372 DEBUG stream UniDi creatable client | 7.372 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.372 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.372 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.373 DEBUG [Client a3cd494a014b0a9809] delay duration 98.900407ms client | 7.373 DEBUG Setting timeout of 98.900407ms client | 7.373 DEBUG [Client a3cd494a014b0a9809] pn=3 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Crypto { offset: 3504, len: 1213 } client | 7.373 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.373 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 291528606 } client | 7.373 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.373 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.373 DEBUG [Client a3cd494a014b0a9809] pn=3 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 3, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 7.373 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.373 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.374 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.374 DEBUG [Client a3cd494a014b0a9809] delay duration 97.903723ms client | 7.374 DEBUG Setting timeout of 97.903723ms client | 7.374 DEBUG stream BiDi creatable client | 7.374 WARN Cannot create stream ConnectionState client | 7.374 DEBUG stream UniDi creatable client | 7.374 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.374 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.374 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.374 DEBUG [Client a3cd494a014b0a9809] delay duration 97.876282ms client | 7.374 DEBUG Setting timeout of 97.876282ms client | 7.374 DEBUG [Client a3cd494a014b0a9809] pn=4 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Crypto { offset: 4717, len: 1213 } client | 7.375 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.375 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 292548336 } client | 7.375 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.375 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.375 DEBUG [Client a3cd494a014b0a9809] pn=4 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 9, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 7.375 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.375 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.375 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.375 DEBUG [Client a3cd494a014b0a9809] delay duration 96.826607ms client | 7.375 DEBUG Setting timeout of 96.826607ms client | 7.375 DEBUG stream BiDi creatable client | 7.375 WARN Cannot create stream ConnectionState client | 7.375 DEBUG stream UniDi creatable client | 7.375 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.375 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.375 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.375 DEBUG [Client a3cd494a014b0a9809] delay duration 96.7962ms client | 7.375 DEBUG Setting timeout of 96.7962ms client | 7.375 DEBUG [Client a3cd494a014b0a9809] pn=5 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1212 client | -> RX Crypto { offset: 5930, len: 1173 } client | 7.375 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.375 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 293539299 } client | 7.375 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.375 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.375 DEBUG [Client a3cd494a014b0a9809] pn=5 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 7.376 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.376 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.376 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.376 DEBUG [Client a3cd494a014b0a9809] delay duration 95.878664ms client | 7.376 DEBUG Setting timeout of 95.878664ms client | 7.376 DEBUG stream BiDi creatable client | 7.376 WARN Cannot create stream ConnectionState client | 7.376 DEBUG stream UniDi creatable client | 7.376 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.376 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.376 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.376 DEBUG [Client a3cd494a014b0a9809] delay duration 95.852264ms client | 7.376 DEBUG Setting timeout of 95.852264ms client | 7.404 DEBUG [Client a3cd494a014b0a9809] pn=6 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Crypto { offset: 7103, len: 1213 } client | 7.404 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.404 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 322401611 } client | 7.404 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.404 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.404 DEBUG [Client a3cd494a014b0a9809] pn=6 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 4, first_ack_range: 6, ack_ranges: [], ecn_count: None } client | 7.404 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.404 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.404 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.404 DEBUG [Client a3cd494a014b0a9809] delay duration 67.00963ms client | 7.404 DEBUG Setting timeout of 67.00963ms client | 7.404 DEBUG stream BiDi creatable client | 7.404 WARN Cannot create stream ConnectionState client | 7.404 DEBUG stream UniDi creatable client | 7.404 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.404 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.404 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.404 DEBUG [Client a3cd494a014b0a9809] delay duration 66.972951ms client | 7.404 DEBUG Setting timeout of 66.972951ms client | 7.405 DEBUG [Client a3cd494a014b0a9809] pn=7 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Crypto { offset: 8316, len: 1213 } client | 7.405 DEBUG [Agent 0x55ebd34f8500] state -> InProgress client | 7.405 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 323388496 } client | 7.405 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.405 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.405 DEBUG [Client a3cd494a014b0a9809] pn=7 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 4, first_ack_range: 7, ack_ranges: [], ecn_count: None } client | 7.405 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.405 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.405 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.405 DEBUG [Client a3cd494a014b0a9809] delay duration 66.029857ms client | 7.405 DEBUG Setting timeout of 66.029857ms client | 7.405 DEBUG stream BiDi creatable client | 7.405 WARN Cannot create stream ConnectionState client | 7.405 DEBUG stream UniDi creatable client | 7.405 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.405 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.405 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.405 DEBUG [Client a3cd494a014b0a9809] delay duration 66.001033ms client | 7.405 DEBUG Setting timeout of 66.001033ms client | 7.406 DEBUG [Client a3cd494a014b0a9809] pn=8 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 485 client | -> RX Crypto { offset: 9529, len: 446 } client | 7.407 DEBUG [Agent 0x55ebd34f8500] state -> AuthenticationPending client | 7.407 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 323806067 } client | 7.407 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.407 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.407 DEBUG [Client a3cd494a014b0a9809] pn=8 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 40 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 119, first_ack_range: 8, ack_ranges: [], ecn_count: None } client | 7.407 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.407 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.407 DEBUG [Client a3cd494a014b0a9809] delay duration 64.689911ms client | 7.407 DEBUG Setting timeout of 64.689911ms client | 7.407 DEBUG [Client a3cd494a014b0a9809] Authenticated Ok client | 7.407 DEBUG Write secret available for ApplicationData: SymKey [48]: 46cb70393fcb23725f3f0e849eb9f77479c4951c0d00c424cebdfb2f137bcc171e35cffac5511bad5322d36a3b346daa client | 7.407 DEBUG Read secret available for ApplicationData: SymKey [48]: 42a0bb44b057d92bb5813549c210923517cfcb19b8ad38f08daa2d5a81a75307c62abff4735d1118c466f8b296e1c4e4 client | 7.407 DEBUG [Agent 0x55ebd34f8500] SSL_AuthCertificateComplete: Ok(()) client | 7.407 DEBUG [Agent 0x55ebd34f8500] 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 | 7.407 DEBUG [Client a3cd494a014b0a9809] TLS connection complete client | 7.407 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4866 min_pn=0 client | 7.407 DEBUG [Crypto] Application write key installed client | 7.407 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4866 min_pn=0 client | 7.407 DEBUG [Crypto] application read keys installed client | 7.407 DEBUG [Client a3cd494a014b0a9809] State change from Handshaking -> Connected client | 7.407 INFO [Client a3cd494a014b0a9809] Connection established client | 7.407 DEBUG stream BiDi creatable client | 7.407 INFO Created stream 0 for https://server4/khibedvnxa client | 7.407 INFO Saving https://server4/khibedvnxa to "/downloads/khibedvnxa" client | 7.407 DEBUG stream UniDi creatable client | 7.407 DEBUG StateChange(Connected) client | 7.407 DEBUG stream 0 writable client | 7.407 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.407 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.407 DEBUG CRYPTO for hs offset=0, len=52 client | 7.407 DEBUG [Client a3cd494a014b0a9809] pn=9 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 89 client | TX -> Crypto { offset: 0, len: 52 } client | 7.407 DEBUG packet_sent this=0x55ebd350baf0, pn=9, ps=89 client | 7.407 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.407 DEBUG [Client a3cd494a014b0a9809] pn=0 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 135 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 7.407 DEBUG packet_sent this=0x55ebd350baf0, pn=0, ps=46 client | 7.407 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.407 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.407 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.407 DEBUG [Client a3cd494a014b0a9809] delay duration 100.123014ms client | 7.407 DEBUG Setting timeout of 100.123014ms client | 7.439 DEBUG [Client a3cd494a014b0a9809] pn=9 type=Handshake pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 38 client | -> RX Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | 7.439 DEBUG [Client a3cd494a014b0a9809] Rx ACK space=hs, ranges=[0..=9] client | 7.439 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=9 client | 7.439 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 46/2593 ssthresh 8764] slow start += 89 client | 7.439 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 46/2593 ssthresh 8764] on_packets_acked this=0x55ebd350baf0, limited=0, bytes_in_flight=46, cwnd=2593, state=SlowStart, new_acked=89 client | 7.439 DEBUG Acked crypto frame space=hs offset=0 length=52 client | 7.439 DEBUG [Client a3cd494a014b0a9809] pn=0 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 568 client | -> RX Crypto { offset: 0, len: 546 } client | 7.440 DEBUG [0x55ebd34f8500] Got resumption token [5994]: 0200063e8b460703..4016bfd20853a7e4 client | 7.440 DEBUG [0x55ebd34f8500] Got resumption token [5994]: 0200063e8b460703..db696a841f307336 client | 7.440 DEBUG [Agent 0x55ebd34f8500] 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 | 7.440 DEBUG setting max_stream_data to 1474200 client | 7.440 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 377478652 } client | 7.440 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.440 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.440 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.440 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.440 DEBUG [Client a3cd494a014b0a9809] delay duration 19.710438ms client | 7.440 DEBUG Setting timeout of 19.710438ms client | 7.440 DEBUG stream BiDi creatable client | 7.440 DEBUG stream UniDi creatable client | 7.440 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.440 DEBUG Building Handshake dcid Some(CID [8]: 6a3d47bebf0d390e) scid Some(CID [0]: ) client | 7.440 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.440 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.440 DEBUG [Client a3cd494a014b0a9809] delay duration 19.673158ms client | 7.440 DEBUG Setting timeout of 19.673158ms client | 7.440 DEBUG [Client a3cd494a014b0a9809] pn=1 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->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, 90, 170, 186, 243, 254, 0, 124, 55, 90, 166, 210, 210, 38, 177, 179, 212, 159, 52, 31, 208, 152, 46, 125, 33, 55, 31, 1, 120, 12, 206, 3, 66, 159, 18, 113, 208] } client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [96, 140, 214, 186, 253, 77, 13, 178], stateless_reset_token: [124, 189, 88, 3, 61, 127, 149, 149, 84, 12, 111, 99, 115, 80, 253, 249] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [63, 101, 90, 246, 162, 156, 115, 97], stateless_reset_token: [133, 69, 67, 216, 148, 77, 102, 197, 47, 109, 35, 10, 156, 117, 46, 227] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [236, 100, 170, 64, 18, 195, 209, 117], stateless_reset_token: [43, 216, 57, 121, 14, 43, 74, 91, 140, 172, 107, 162, 240, 247, 7, 108] } client | 7.440 DEBUG [Client a3cd494a014b0a9809] Rx ACK space=ap, ranges=[0..=0] client | 7.440 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=0 client | 7.440 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/2639 ssthresh 8764] slow start += 46 client | 7.440 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/2639 ssthresh 8764] on_packets_acked this=0x55ebd350baf0, limited=0, bytes_in_flight=0, cwnd=2639, state=SlowStart, new_acked=46 client | 7.440 DEBUG [Client a3cd494a014b0a9809] State change from Connected -> Confirmed client | 7.440 DEBUG PMTUD started with probe size 1380 client | 7.440 DEBUG [Client a3cd494a014b0a9809] Drop packet number space hs client | 7.440 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 7.440 DEBUG [Client a3cd494a014b0a9809] No preferred address to migrate to client | 7.440 DEBUG resumption token [6138]: 0000000124406300..db696a841f307336 client | 7.440 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 358463674 } client | 7.440 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.440 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.440 DEBUG Sending PMTUD probe of size 1380, count 1 client | 7.440 DEBUG [Client a3cd494a014b0a9809] pn=1 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 12, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> Ping client | TX -> Padding { len: 1320 } client | 7.440 DEBUG packet_sent this=0x55ebd350baf0, pn=1, ps=1352 client | 7.440 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.440 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.440 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.440 DEBUG ECN probing: sent 1 probes client | 7.441 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.441 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.441 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.441 DEBUG [Client a3cd494a014b0a9809] delay duration 105.741907ms client | 7.441 DEBUG Setting timeout of 105.741907ms client | 7.441 DEBUG stream 0 complete client | 7.441 WARN Unhandled event StateChange(Confirmed) client | 7.441 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.441 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.441 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.441 DEBUG [Client a3cd494a014b0a9809] delay duration 105.713885ms client | 7.441 DEBUG Setting timeout of 105.713885ms client | 7.443 DEBUG [Client a3cd494a014b0a9809] pn=2 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 0, len: 1230, fin: false } client | 7.443 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 380728601 } client | 7.443 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.443 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.443 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.443 DEBUG [Client a3cd494a014b0a9809] delay duration 19.973331ms client | 7.443 DEBUG Setting timeout of 19.973331ms client | 7.443 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.443 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.443 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.443 DEBUG [Client a3cd494a014b0a9809] delay duration 19.952422ms client | 7.443 DEBUG Setting timeout of 19.952422ms client | 7.444 DEBUG [Client a3cd494a014b0a9809] pn=3 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 1230, len: 1228, fin: false } client | 7.444 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 361711601 } client | 7.444 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.444 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.444 DEBUG [Client a3cd494a014b0a9809] pn=2 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 4, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 7.444 DEBUG ECN probing: sent 2 probes client | 7.444 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.444 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.444 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.444 DEBUG [Client a3cd494a014b0a9809] delay duration 102.581735ms client | 7.444 DEBUG Setting timeout of 102.581735ms client | 7.444 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.444 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.444 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.444 DEBUG [Client a3cd494a014b0a9809] delay duration 102.557179ms client | 7.444 DEBUG Setting timeout of 102.557179ms client | 7.447 DEBUG [Client a3cd494a014b0a9809] pn=4 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 2458, len: 1228, fin: false } client | 7.447 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 385343834 } client | 7.447 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.447 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.447 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.447 DEBUG [Client a3cd494a014b0a9809] delay duration 19.972449ms client | 7.447 DEBUG Setting timeout of 19.972449ms client | 7.447 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.447 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.447 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.447 DEBUG [Client a3cd494a014b0a9809] delay duration 19.954816ms client | 7.447 DEBUG Setting timeout of 19.954816ms client | 7.448 DEBUG [Client a3cd494a014b0a9809] pn=5 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | -> RX Stream { stream_id: 0, offset: 3686, len: 1228, fin: false } client | 7.448 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 366358882 } client | 7.448 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.448 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.448 DEBUG [Client a3cd494a014b0a9809] pn=3 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 2, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 7.448 DEBUG ECN probing: sent 3 probes client | 7.448 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.448 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.448 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.448 DEBUG [Client a3cd494a014b0a9809] delay duration 97.95401ms client | 7.448 DEBUG Setting timeout of 97.95401ms client | 7.448 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.448 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.448 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.448 DEBUG [Client a3cd494a014b0a9809] delay duration 97.930546ms client | 7.448 DEBUG Setting timeout of 97.930546ms client | 7.450 DEBUG [Client a3cd494a014b0a9809] pn=6 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, NotEct) len 230 client | -> RX Stream { stream_id: 0, offset: 4914, len: 206, fin: true } client | 7.450 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 552, tv_nsec: 387987388 } client | 7.450 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.450 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.450 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.450 DEBUG [Client a3cd494a014b0a9809] delay duration 19.958021ms client | 7.450 DEBUG Setting timeout of 19.958021ms client | 7.450 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.450 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.450 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.450 DEBUG [Client a3cd494a014b0a9809] delay duration 19.908278ms client | 7.450 DEBUG Setting timeout of 19.908278ms client | 7.450 DEBUG [Client a3cd494a014b0a9809] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 552, tv_nsec: 685550842 } } client | 7.450 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 552, tv_nsec: 685550842 } }) client | 7.450 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.450 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.450 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 552, tv_nsec: 368110318 } client | 7.450 DEBUG [Client a3cd494a014b0a9809] pn=4 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, Ect0) len 42 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 15, first_ack_range: 6, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.450 DEBUG ECN probing: sent 4 probes client | 7.450 DEBUG Setting timeout of 317.375372ms client | 7.481 DEBUG [Client a3cd494a014b0a9809] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.481 DEBUG Building Short dcid Some(CID [8]: 6a3d47bebf0d390e) client | 7.481 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 552, tv_nsec: 399078736 } client | 7.481 DEBUG [Client a3cd494a014b0a9809] pn=5 type=Short pri-path:6a3d47bebf0d390e 0.0.0.0:57833->193.167.100.100:443 Tos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 3886, first_ack_range: 6, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.481 DEBUG ECN probing: sent 5 probes client | 7.481 DEBUG Setting timeout of 286.402235ms client | 7.481 DEBUG Setting timeout of 286.390333ms client | 7.769 DEBUG [Client a3cd494a014b0a9809] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 552, tv_nsec: 685550842 } } -> Closed(Application(0)) client | 7.769 INFO Closing timer expired client | 7.769 DEBUG Output::None client | 7.769 WARN Unhandled event StateChange(Closed(Application(0))) client | 7.769 DEBUG Timer fired while closed client | 7.769 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-09-11 19:09:27,332 Using the client's key log file. 2025-09-11 19:09:27,344 2025-09-11 19:09:27,345 Using the client's key log file. 2025-09-11 19:09:27,345 Using the client's key log file. 2025-09-11 19:09:27,356 2025-09-11 19:09:27,356 Using the client's key log file. 2025-09-11 19:09:27,356 Using the client's key log file. 2025-09-11 19:09:27,858 Check of downloaded files succeeded. 2025-09-11 19:09:28,108 Server sent 9975 bytes in Handshake CRYPTO frames. 2025-09-11 19:09:28,403 Received a 1252 byte Initial packet from the client. Amplification limit: 3756 2025-09-11 19:09:28,403 Received a 40 byte Handshake packet from the server. Total: 40 2025-09-11 19:09:28,403 Received a 1252 byte Initial packet from the client. Amplification limit: 7512 2025-09-11 19:09:28,403 Received a 1252 byte Handshake packet from the server. Total: 1292 2025-09-11 19:09:28,404 Received a 1252 byte Handshake packet from the server. Total: 2544 2025-09-11 19:09:28,404 Received a 1252 byte Handshake packet from the server. Total: 3796 2025-09-11 19:09:28,404 Received a 1252 byte Handshake packet from the server. Total: 5048 2025-09-11 19:09:28,404 Received a 1252 byte Handshake packet from the server. Total: 6300 2025-09-11 19:09:28,404 Received a 1212 byte Handshake packet from the server. Total: 7512 2025-09-11 19:09:28,404 Received a 81 byte Initial packet from the client. Amplification limit: 7755