2025-12-13 11:38:00,735 Generated random file: minuscule-ample-bicycle of size: 5120 2025-12-13 11:38:00,735 Requests: https://server4:443/minuscule-ample-bicycle 2025-12-13 11:38:01,109 2025-12-13 11:38:01,109 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_d0kxwoo1/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_x4uofe3d/ DOWNLOADS=/tmp/download_xfsz4ou9/ SERVER_LOGS=/tmp/logs_server_16vhfeq4 CLIENT_LOGS=/tmp/logs_client_dsefatfy 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=litespeedtech/lsquic-qir:latest REQUESTS="https://server4:443/minuscule-ample-bicycle" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-12-13 11:38:13,684 Container sim Recreate Container sim Recreated Container server Recreate Container client Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... server | Actual changes: server | tx-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 | TEST_PARAMS: server | REQUESTS: '' client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | paths: server | server: server | port: server | SERVER_PARAMS: 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 sim | server:443 is available after 501.313868ms sim | Using scenario: droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7 sim | Capturing on 'eth1' sim | Capturing on 'eth0' sim | File: /logs/trace_node_left.pcap sim | File: /logs/trace_node_right.pcap 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/minuscule-ample-bicycle ']' 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/minuscule-ample-bicycle 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:38939 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=edf16b735c773568 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=13 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:38939->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:edf16b735c773568 0.0.0.0:38939->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:edf16b735c773568 0.0.0.0:38939->193.167.100.100:443] Path validated Instant { tv_sec: 380, tv_nsec: 799294103 } client | 0.001 DEBUG [Client edf16b735c773568] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.002 DEBUG [Client edf16b735c773568] State change from Init -> WaitInitial client | 0.002 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: edf16b735c773568) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1213 client | 0.002 DEBUG [Client edf16b735c773568] pn=13 type=Initial pri-path:edf16b735c773568 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.002 DEBUG packet_sent this=0x558134953d40, pn=13, ps=1252 client | 0.002 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: edf16b735c773568) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=325 client | 0.002 DEBUG [Client edf16b735c773568] pn=14 type=Initial pri-path:edf16b735c773568 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 365 client | TX -> Crypto { offset: 1213, len: 325 } client | 0.002 DEBUG [Client edf16b735c773568] pad Initial from 365 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x558134953d40, pn=14, ps=1252 client | 0.002 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: edf16b735c773568) 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 edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: edf16b735c773568) 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 edf16b735c773568] delay duration 3.312153ms client | 0.002 DEBUG Setting timeout of 3.312153ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: edf16b735c773568) 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 edf16b735c773568] delay duration 3.26233ms client | 0.002 DEBUG Setting timeout of 3.26233ms client | 0.006 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.006 DEBUG Building Initial dcid Some(CID [8]: edf16b735c773568) scid Some(CID [0]: ) client | 0.006 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.006 DEBUG [Client edf16b735c773568] delay duration 294.705146ms client | 0.006 DEBUG Setting timeout of 294.705146ms sim | Packets: 1 Packets: 1 Packets: 4 Packets: 6 Dropping packet 2 (1252 bytes) from 193.167.0.100 client | 0.039 INFO [Client edf16b735c773568] received Retry client | 0.039 INFO [Client edf16b735c773568] Valid Retry received, token=0069fd383f28ae5948b8da2705e326f2f99ba5d1e07b9354372db0506a5f8099505fce5cdad9090c95cf841e8b9ce276303bb8d9 scid=b0def87e55490663 client | 0.039 INFO [pri-path:b0def87e55490663 0.0.0.0:38939->193.167.100.100:443] discarding a packet without an RTT estimate; guessing RTT=38.05555ms client | 0.039 DEBUG [Client edf16b735c773568] 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 edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 325 }) client | 0.039 INFO Lost crypto frame space=in offset=1213 length=325 client | 0.039 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=b0def87e55490663 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 edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.040 DEBUG CRYPTO for in offset=0, len=1161 client | 0.040 DEBUG [Client edf16b735c773568] pn=15 type=Initial pri-path:b0def87e55490663 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1161 } client | 0.040 DEBUG packet_sent this=0x558134953d40, pn=15, ps=1252 client | 0.040 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.040 DEBUG CRYPTO for in offset=1161, len=377 client | 0.040 DEBUG [Client edf16b735c773568] pn=16 type=Initial pri-path:b0def87e55490663 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 469 client | TX -> Crypto { offset: 1161, len: 377 } client | 0.040 DEBUG [Client edf16b735c773568] pad Initial from 469 to PLPMTU 1252 client | 0.040 DEBUG packet_sent this=0x558134953d40, pn=16, ps=1252 client | 0.040 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) 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 edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) 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 edf16b735c773568] delay duration 114.047446ms client | 0.040 DEBUG Setting timeout of 114.047446ms client | 0.040 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.040 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) 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 edf16b735c773568] delay duration 114.018742ms client | 0.040 DEBUG Setting timeout of 114.018742ms sim | Dropping packet 3 (1252 bytes) from 193.167.0.100 sim | Dropping packet 4 (1252 bytes) from 193.167.0.100 client | 0.155 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.155 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1161 }) client | 0.155 INFO Lost crypto frame space=in offset=0 length=1161 client | 0.155 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1161, length: 377 }) client | 0.155 INFO Lost crypto frame space=in offset=1161 length=377 client | 0.155 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.155 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.155 DEBUG CRYPTO for in offset=0, len=1161 client | 0.155 DEBUG [Client edf16b735c773568] pn=17 type=Initial pri-path:b0def87e55490663 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1161 } client | 0.155 DEBUG packet_sent this=0x558134953d40, pn=17, ps=1252 client | 0.155 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.155 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.155 DEBUG CRYPTO for in offset=1161, len=377 client | 0.155 DEBUG [Client edf16b735c773568] pn=18 type=Initial pri-path:b0def87e55490663 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 469 client | TX -> Crypto { offset: 1161, len: 377 } client | 0.155 DEBUG [Client edf16b735c773568] pad Initial from 469 to PLPMTU 1252 client | 0.155 DEBUG packet_sent this=0x558134953d40, pn=18, ps=1252 client | 0.155 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.155 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.155 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.155 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.155 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.155 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.155 DEBUG [Client edf16b735c773568] delay duration 228.170835ms client | 0.155 DEBUG Setting timeout of 228.170835ms sim | Packets: 10 Packets: 11 Dropping packet 5 (1252 bytes) from 193.167.0.100 sim | Dropping packet 6 (1252 bytes) from 193.167.0.100 client | 0.384 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.384 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1161 }) client | 0.384 INFO Lost crypto frame space=in offset=0 length=1161 client | 0.384 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1161, length: 377 }) client | 0.384 INFO Lost crypto frame space=in offset=1161 length=377 client | 0.384 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.384 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.384 DEBUG CRYPTO for in offset=0, len=1161 client | 0.384 DEBUG [Client edf16b735c773568] pn=19 type=Initial pri-path:b0def87e55490663 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1161 } client | 0.384 DEBUG packet_sent this=0x558134953d40, pn=19, ps=1252 client | 0.384 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.384 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.385 DEBUG CRYPTO for in offset=1161, len=377 client | 0.385 DEBUG [Client edf16b735c773568] pn=20 type=Initial pri-path:b0def87e55490663 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 469 client | TX -> Crypto { offset: 1161, len: 377 } client | 0.385 DEBUG [Client edf16b735c773568] pad Initial from 469 to PLPMTU 1252 client | 0.385 DEBUG packet_sent this=0x558134953d40, pn=20, ps=1252 client | 0.385 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.385 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.385 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.385 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.385 DEBUG Building Initial dcid Some(CID [8]: b0def87e55490663) scid Some(CID [0]: ) client | 0.385 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.385 DEBUG [Client edf16b735c773568] delay duration 456.389407ms client | 0.385 DEBUG Setting timeout of 456.389407ms sim | Packets: 19 Packets: 37 Dropping packet 7 (1252 bytes) from 193.167.0.100 client | 0.417 DEBUG [Client edf16b735c773568] pn=0 type=Initial pri-path:b0def87e55490663 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 40 client | -> RX Ack { largest_acknowledged: 20, ack_delay: 17, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.417 DEBUG [Client edf16b735c773568] Rx ACK space=in, ranges=[20..=20] client | 0.417 DEBUG [recovery::Loss] ACK for Initial - largest_acked=20 client | 0.417 DEBUG packet_lost this=0x558134953d40, pn=15, ps=1252 client | 0.417 DEBUG packet_lost this=0x558134953d40, pn=16, ps=1252 client | 0.417 DEBUG packet_lost this=0x558134953d40, pn=17, ps=1252 client | 0.417 DEBUG packet_lost this=0x558134953d40, pn=18, ps=1252 client | 0.417 INFO [Cubic [w_max: 12520, k: 0, t_epoch: None] CongCtrl 2504/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764 client | 0.417 DEBUG [Cubic [w_max: 12520, k: 0, t_epoch: None] CongCtrl 2504/8764 ssthresh 8764] state -> RecoveryStart client | 0.417 DEBUG on_packets_lost this=0x558134953d40, bytes_in_flight=2504, cwnd=8764, state=RecoveryStart client | 0.417 DEBUG [Cubic [w_max: 12520, k: 1.9574338205844317, t_epoch: Some(Instant { tv_sec: 381, tv_nsec: 216080884 })] CongCtrl 1252/8764 ssthresh 8764] on_packets_acked this=0x558134953d40, limited=0, bytes_in_flight=1252, cwnd=8764, state=RecoveryStart, new_acked=0 client | 0.417 DEBUG Acked crypto frame space=in offset=1161 length=377 client | 0.417 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1161 }) client | 0.417 INFO Lost crypto frame space=in offset=0 length=1161 client | 0.417 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1161, length: 377 }) client | 0.417 INFO Lost crypto frame space=in offset=1161 length=377 client | 0.417 DEBUG Attempted to unmark Acked range 1161-377 with unmark_range 1161-1538 client | 0.417 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1161 }) client | 0.417 INFO Lost crypto frame space=in offset=0 length=1161 client | 0.417 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1161, length: 377 }) client | 0.417 INFO Lost crypto frame space=in offset=1161 length=377 client | 0.417 DEBUG Attempted to unmark Acked range 1161-377 with unmark_range 1161-1538 client | 0.417 DEBUG [Client edf16b735c773568] Changing to use Server CID=91c1438a00d06830 client | 0.417 DEBUG [Client edf16b735c773568] State change from WaitInitial -> WaitVersion client | 0.417 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.417 DEBUG Building Initial dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.417 DEBUG CRYPTO for in offset=0, len=1161 client | 0.418 DEBUG [Client edf16b735c773568] pn=21 type=Initial pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1161 } client | 0.418 DEBUG packet_sent this=0x558134953d40, pn=21, ps=1252 client | 0.418 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.418 DEBUG Building Initial dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.418 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.418 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.418 DEBUG Building Initial dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.418 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.418 DEBUG [Client edf16b735c773568] delay duration 8.923891ms client | 0.418 DEBUG Setting timeout of 8.923891ms client | 0.418 WARN Unhandled event StateChange(WaitVersion) client | 0.418 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.418 DEBUG Building Initial dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.418 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.418 DEBUG [Client edf16b735c773568] delay duration 8.893433ms client | 0.418 DEBUG Setting timeout of 8.893433ms client | 0.428 DEBUG packet_lost this=0x558134953d40, pn=19, ps=1252 client | 0.428 DEBUG on_packets_lost this=0x558134953d40, bytes_in_flight=1252, cwnd=8764, state=Recovery client | 0.428 DEBUG [Client edf16b735c773568] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1161 }) client | 0.428 INFO Lost crypto frame space=in offset=0 length=1161 client | 0.428 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.428 DEBUG Building Initial dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.428 DEBUG CRYPTO for in offset=0, len=1161 client | 0.428 DEBUG [Client edf16b735c773568] pn=22 type=Initial pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1161 } client | 0.428 DEBUG packet_sent this=0x558134953d40, pn=22, ps=1252 client | 0.428 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.428 DEBUG Building Initial dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.428 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.428 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.428 DEBUG Building Initial dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.428 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.428 DEBUG [Client edf16b735c773568] delay duration 99.435047ms client | 0.428 DEBUG Setting timeout of 99.435047ms client | 0.451 DEBUG [Client edf16b735c773568] pn=1 type=Initial pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 135 client | -> RX Ack { largest_acknowledged: 21, ack_delay: 136, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | 0.451 DEBUG [Client edf16b735c773568] Rx ACK space=in, ranges=[20..=21] client | 0.451 DEBUG [recovery::Loss] ACK for Initial - largest_acked=21 client | 0.451 DEBUG [Cubic [w_max: 12520, k: 1.9574338205844317, t_epoch: Some(Instant { tv_sec: 381, tv_nsec: 216080884 })] CongCtrl 1252/8764 ssthresh 8764] state -> CongestionAvoidance client | 0.451 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=1252, cwnd=8764, state=CongestionAvoidance, new_acked=1252 client | 0.451 DEBUG Acked crypto frame space=in offset=0 length=1161 client | 0.452 DEBUG Write secret available for Handshake: SymKey [32]: 347a5ba24617f38d8fba2162d5b55c246e36a5b671acc9066b531dd39a28aa8c client | 0.452 DEBUG Read secret available for Handshake: SymKey [32]: 21a0e1bcddefbdb090df83e0ff45adc04a44f8859ee0b240a5db4fd5ca44b103 client | 0.452 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.452 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.452 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.452 DEBUG [Crypto] Handshake keys installed client | 0.452 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 250058263 } client | 0.452 DEBUG [Client edf16b735c773568] State change from WaitVersion -> Handshaking client | 0.452 DEBUG [Client edf16b735c773568] pn=2 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1117 client | -> RX Crypto { offset: 0, len: 1079 } client | 0.452 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.452 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 250058263 } client | 0.452 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG Building Initial dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.452 DEBUG [Client edf16b735c773568] pn=23 type=Initial pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 96 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 77, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | 0.452 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.452 DEBUG [Client edf16b735c773568] pn=0 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 139 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 77, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 0.452 DEBUG [Client edf16b735c773568] Drop packet number space in client | 0.452 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 0.452 DEBUG [Client edf16b735c773568] pad Initial from 139 to PLPMTU 1252 client | 0.452 INFO [recovery::Loss] ignoring packet 23 from dropped space in client | 0.452 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.452 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.452 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG [Client edf16b735c773568] delay duration 87.02127ms client | 0.452 DEBUG Setting timeout of 87.02127ms client | 0.452 DEBUG StateChange(Handshaking) client | 0.452 WARN Cannot create stream ConnectionState client | 0.452 DEBUG stream BiDi creatable client | 0.452 WARN Cannot create stream ConnectionState client | 0.452 DEBUG stream UniDi creatable client | 0.452 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.452 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG [Client edf16b735c773568] delay duration 86.986886ms client | 0.452 DEBUG Setting timeout of 86.986886ms client | 0.452 DEBUG [Client edf16b735c773568] pn=3 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Crypto { offset: 1079, len: 1213 } client | 0.452 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.452 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 251075818 } client | 0.452 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.452 DEBUG [Client edf16b735c773568] pn=1 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 4, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | 0.452 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.452 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG [Client edf16b735c773568] delay duration 86.652173ms client | 0.452 DEBUG Setting timeout of 86.652173ms client | 0.452 DEBUG stream BiDi creatable client | 0.452 WARN Cannot create stream ConnectionState client | 0.452 DEBUG stream UniDi creatable client | 0.452 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.452 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.452 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.453 DEBUG [Client edf16b735c773568] delay duration 86.626665ms client | 0.453 DEBUG Setting timeout of 86.626665ms client | 0.453 DEBUG [Client edf16b735c773568] pn=4 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Crypto { offset: 2292, len: 1213 } client | 0.453 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.453 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 252079689 } client | 0.453 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.453 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.453 DEBUG [Client edf16b735c773568] pn=2 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 5, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } client | 0.454 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.454 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG [Client edf16b735c773568] delay duration 85.618025ms client | 0.454 DEBUG Setting timeout of 85.618025ms client | 0.454 DEBUG stream BiDi creatable client | 0.454 WARN Cannot create stream ConnectionState client | 0.454 DEBUG stream UniDi creatable client | 0.454 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.454 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG [Client edf16b735c773568] delay duration 85.589041ms client | 0.454 DEBUG Setting timeout of 85.589041ms client | 0.454 DEBUG [Client edf16b735c773568] pn=5 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Crypto { offset: 3505, len: 1213 } client | 0.454 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.454 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 253068919 } client | 0.454 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.454 DEBUG [Client edf16b735c773568] pn=3 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 5, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } client | 0.454 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.454 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG [Client edf16b735c773568] delay duration 84.653401ms client | 0.454 DEBUG Setting timeout of 84.653401ms client | 0.454 DEBUG stream BiDi creatable client | 0.454 WARN Cannot create stream ConnectionState client | 0.454 DEBUG stream UniDi creatable client | 0.454 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.454 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.454 DEBUG [Client edf16b735c773568] delay duration 84.626501ms client | 0.455 DEBUG Setting timeout of 84.626501ms client | 0.455 DEBUG [Client edf16b735c773568] pn=6 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Crypto { offset: 4718, len: 1213 } client | 0.455 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.455 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 254139931 } client | 0.455 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.455 DEBUG [Client edf16b735c773568] pn=4 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 4, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } client | 0.456 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.456 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.456 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.456 DEBUG [Client edf16b735c773568] delay duration 83.596637ms client | 0.456 DEBUG Setting timeout of 83.596637ms client | 0.456 DEBUG stream BiDi creatable client | 0.456 WARN Cannot create stream ConnectionState client | 0.456 DEBUG stream UniDi creatable client | 0.456 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.456 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.456 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.456 DEBUG [Client edf16b735c773568] delay duration 83.569055ms client | 0.456 DEBUG Setting timeout of 83.569055ms client | 0.456 DEBUG [Client edf16b735c773568] pn=7 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Crypto { offset: 5931, len: 1213 } client | 0.456 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.456 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 255163894 } client | 0.456 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.456 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.456 DEBUG [Client edf16b735c773568] pn=5 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 3, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } client | 0.457 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.457 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.457 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.457 DEBUG [Client edf16b735c773568] delay duration 82.583113ms client | 0.457 DEBUG Setting timeout of 82.583113ms client | 0.457 DEBUG stream BiDi creatable client | 0.457 WARN Cannot create stream ConnectionState client | 0.457 DEBUG stream UniDi creatable client | 0.457 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.457 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.457 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.457 DEBUG [Client edf16b735c773568] delay duration 82.556654ms client | 0.457 DEBUG Setting timeout of 82.556654ms client | 0.457 DEBUG [Client edf16b735c773568] pn=8 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Crypto { offset: 7144, len: 1213 } client | 0.458 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.458 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 256191416 } client | 0.458 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.458 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.458 DEBUG [Client edf16b735c773568] pn=6 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 3, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } client | 0.458 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.458 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.458 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.458 DEBUG [Client edf16b735c773568] delay duration 81.548197ms client | 0.458 DEBUG Setting timeout of 81.548197ms client | 0.458 DEBUG stream BiDi creatable client | 0.458 WARN Cannot create stream ConnectionState client | 0.458 DEBUG stream UniDi creatable client | 0.458 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.458 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.458 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.458 DEBUG [Client edf16b735c773568] delay duration 81.52265ms client | 0.458 DEBUG Setting timeout of 81.52265ms client | 0.459 DEBUG [Client edf16b735c773568] pn=9 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Crypto { offset: 8357, len: 1213 } client | 0.459 DEBUG [Agent 0x558134940c60] state -> InProgress client | 0.459 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 257212584 } client | 0.459 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.459 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.459 DEBUG [Client edf16b735c773568] pn=7 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 3, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } client | 0.459 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.459 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.459 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.459 DEBUG [Client edf16b735c773568] delay duration 80.533381ms client | 0.459 DEBUG Setting timeout of 80.533381ms client | 0.459 DEBUG stream BiDi creatable client | 0.459 WARN Cannot create stream ConnectionState client | 0.459 DEBUG stream UniDi creatable client | 0.459 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.459 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.459 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.459 DEBUG [Client edf16b735c773568] delay duration 80.504077ms client | 0.459 DEBUG Setting timeout of 80.504077ms client | 0.459 DEBUG [Client edf16b735c773568] pn=10 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 436 client | -> RX Crypto { offset: 9570, len: 397 } client | 0.460 DEBUG [Agent 0x558134940c60] state -> AuthenticationPending client | 0.460 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 257556157 } client | 0.460 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.460 DEBUG [Client edf16b735c773568] pn=8 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 10, ack_delay: 109, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } client | 0.460 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.460 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG [Client edf16b735c773568] delay duration 79.304977ms client | 0.460 DEBUG Setting timeout of 79.304977ms client | 0.460 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 0.460 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.460 DEBUG [Client edf16b735c773568] pn=9 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 35 client | TX -> Ping client | 0.460 DEBUG packet_sent this=0x558134953d40, pn=9, ps=37 client | 0.460 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.460 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG [Client edf16b735c773568] delay duration 87.094719ms client | 0.460 DEBUG Setting timeout of 87.094719ms client | 0.460 DEBUG [Client edf16b735c773568] Authenticated Ok client | 0.460 DEBUG Write secret available for ApplicationData: SymKey [32]: 9bc4911da057fd53f1d6efebdbb7e6816a478f571dc0572e6e9539ae39ceaf53 client | 0.460 DEBUG Read secret available for ApplicationData: SymKey [32]: afc5f7dd485792f9d39b18581a073d697a50cdbd5e826a77738e2d2fa1853733 client | 0.460 DEBUG [Agent 0x558134940c60] SSL_AuthCertificateComplete: Ok(()) client | 0.460 DEBUG [Agent 0x558134940c60] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.460 DEBUG [Client edf16b735c773568] TLS connection complete client | 0.460 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.460 DEBUG [Crypto] Application write key installed client | 0.460 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.460 DEBUG [Crypto] application read keys installed client | 0.460 DEBUG [Client edf16b735c773568] State change from Handshaking -> Connected client | 0.460 INFO [Client edf16b735c773568] Connection established client | 0.460 DEBUG stream BiDi creatable client | 0.460 INFO Created stream 0 for https://server4/minuscule-ample-bicycle client | 0.460 INFO Saving https://server4/minuscule-ample-bicycle to "/downloads/minuscule-ample-bicycle" client | 0.460 DEBUG stream UniDi creatable client | 0.460 DEBUG StateChange(Connected) client | 0.460 DEBUG stream 0 writable client | 0.460 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.460 DEBUG CRYPTO for hs offset=0, len=36 client | 0.460 DEBUG [Client edf16b735c773568] pn=10 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 0.460 DEBUG packet_sent this=0x558134953d40, pn=10, ps=73 client | 0.460 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.460 DEBUG [Client edf16b735c773568] pn=0 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, NotEct) len 139 client | TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10000, ignore_order: false } client | TX -> Stream { stream_id: 0, offset: 0, len: 30, fin: true } client | 0.460 DEBUG packet_sent this=0x558134953d40, pn=0, ps=66 client | 0.460 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.460 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.460 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.460 DEBUG [Client edf16b735c773568] delay duration 87.035428ms client | 0.460 DEBUG Setting timeout of 87.035428ms client | 0.484 DEBUG [Client edf16b735c773568] pn=12 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.484 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=1] client | 0.484 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=1 client | 0.484 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=176, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.484 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.484 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.484 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.484 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.484 DEBUG [Client edf16b735c773568] delay duration 63.701976ms client | 0.484 DEBUG Setting timeout of 63.701976ms client | 0.484 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.484 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.484 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.484 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.484 DEBUG [Client edf16b735c773568] delay duration 63.673633ms client | 0.484 DEBUG Setting timeout of 63.673633ms client | 0.484 DEBUG [Client edf16b735c773568] pn=13 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 2, ack_delay: 5, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 0.484 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=2] client | 0.484 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=2 client | 0.484 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=176, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.484 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.484 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.484 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.484 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.484 DEBUG [Client edf16b735c773568] delay duration 63.463438ms client | 0.484 DEBUG Setting timeout of 63.463438ms client | 0.484 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.484 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.484 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.484 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.484 DEBUG [Client edf16b735c773568] delay duration 63.436818ms client | 0.484 DEBUG Setting timeout of 63.436818ms client | 0.485 DEBUG [Client edf16b735c773568] pn=14 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 3, ack_delay: 3, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 0.485 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=3] client | 0.485 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=3 client | 0.485 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=176, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.485 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.485 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.485 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.485 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.485 DEBUG [Client edf16b735c773568] delay duration 62.522421ms client | 0.485 DEBUG Setting timeout of 62.522421ms client | 0.485 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.485 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.485 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.485 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.485 DEBUG [Client edf16b735c773568] delay duration 62.503555ms client | 0.485 DEBUG Setting timeout of 62.503555ms client | 0.486 DEBUG [Client edf16b735c773568] pn=15 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 4, ack_delay: 3, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 0.486 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=4] client | 0.486 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=4 client | 0.486 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=176, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.486 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.486 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.486 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.486 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.486 DEBUG [Client edf16b735c773568] delay duration 61.490723ms client | 0.486 DEBUG Setting timeout of 61.490723ms client | 0.486 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.486 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.486 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.486 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.486 DEBUG [Client edf16b735c773568] delay duration 61.471247ms client | 0.486 DEBUG Setting timeout of 61.471247ms client | 0.487 DEBUG [Client edf16b735c773568] pn=16 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 5, ack_delay: 6, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 0.487 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=5] client | 0.487 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=5 client | 0.487 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=176, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.487 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.487 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.487 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.487 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.487 DEBUG [Client edf16b735c773568] delay duration 60.384987ms client | 0.487 DEBUG Setting timeout of 60.384987ms client | 0.487 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.487 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.487 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.487 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.487 DEBUG [Client edf16b735c773568] delay duration 60.362755ms client | 0.487 DEBUG Setting timeout of 60.362755ms client | 0.488 DEBUG [Client edf16b735c773568] pn=17 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 6, ack_delay: 4, first_ack_range: 6, ack_ranges: [], ecn_count: None } client | 0.488 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=6] client | 0.488 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=6 client | 0.488 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=176, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.488 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.488 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.488 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.488 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.488 DEBUG [Client edf16b735c773568] delay duration 59.34824ms client | 0.488 DEBUG Setting timeout of 59.34824ms client | 0.488 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.488 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.488 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.488 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.488 DEBUG [Client edf16b735c773568] delay duration 59.325928ms client | 0.488 DEBUG Setting timeout of 59.325928ms client | 0.489 DEBUG [Client edf16b735c773568] pn=18 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 7, ack_delay: 3, first_ack_range: 7, ack_ranges: [], ecn_count: None } client | 0.489 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=7] client | 0.489 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=7 client | 0.489 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=176, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.489 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.489 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.489 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.489 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.489 DEBUG [Client edf16b735c773568] delay duration 58.406972ms client | 0.489 DEBUG Setting timeout of 58.406972ms client | 0.489 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.489 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.489 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.489 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.489 DEBUG [Client edf16b735c773568] delay duration 58.385622ms client | 0.489 DEBUG Setting timeout of 58.385622ms client | 0.490 DEBUG [Client edf16b735c773568] pn=19 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 8, ack_delay: 3, first_ack_range: 8, ack_ranges: [], ecn_count: None } client | 0.490 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=8] client | 0.490 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=8 client | 0.490 DEBUG on_packets_acked this=0x558134953d40, limited=1, bytes_in_flight=176, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.490 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.490 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.490 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.490 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.490 DEBUG [Client edf16b735c773568] delay duration 57.20803ms client | 0.490 DEBUG Setting timeout of 57.20803ms client | 0.490 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.490 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.490 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.490 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.490 DEBUG [Client edf16b735c773568] delay duration 57.187371ms client | 0.490 DEBUG Setting timeout of 57.187371ms client | 0.490 DEBUG [Client edf16b735c773568] pn=20 type=Handshake pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 9, ack_delay: 3, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | 0.490 DEBUG [Client edf16b735c773568] Rx ACK space=hs, ranges=[0..=9] client | 0.490 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=9 client | 0.490 DEBUG [Cubic [w_max: 12520, k: 1.9574338205844317, t_epoch: Some(Instant { tv_sec: 381, tv_nsec: 289086673 })] CongCtrl 139/8764 ssthresh 8764] on_packets_acked this=0x558134953d40, limited=0, bytes_in_flight=139, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.490 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.490 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.490 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.490 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.490 DEBUG [Client edf16b735c773568] delay duration 50.13311ms client | 0.490 DEBUG Setting timeout of 50.13311ms client | 0.490 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.490 DEBUG Building Handshake dcid Some(CID [8]: 91c1438a00d06830) scid Some(CID [0]: ) client | 0.490 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.490 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.490 DEBUG [Client edf16b735c773568] delay duration 50.113584ms client | 0.490 DEBUG Setting timeout of 50.113584ms client | 0.492 DEBUG [Client edf16b735c773568] pn=21 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 595 client | -> RX Crypto { offset: 0, len: 572 } client | -> RX HandshakeDone client | 0.492 DEBUG [0x558134940c60] Got resumption token [6010]: 02000645d3cf90ac..0c92c986d18ab9e6 client | 0.492 DEBUG [0x558134940c60] Got resumption token [6010]: 02000645d3cf90ac..2f7c1f83966b8294 client | 0.492 DEBUG [Agent 0x558134940c60] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.492 DEBUG setting max_stream_data to 1048576 client | 0.492 DEBUG [Client edf16b735c773568] State change from Connected -> Confirmed client | 0.492 DEBUG PMTUD started, PLPMTU is now 1280 client | 0.492 DEBUG PMTUD started with probe size 1380 client | 0.492 DEBUG [Client edf16b735c773568] Drop packet number space hs client | 0.492 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 0.492 DEBUG [Client edf16b735c773568] No preferred address to migrate to client | 0.492 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 290804211 } client | 0.492 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.492 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.492 DEBUG [Client edf16b735c773568] pn=1 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ack { largest_acknowledged: 21, ack_delay: 22, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | TX -> Ping client | TX -> Padding { len: 1317 } client | 0.492 DEBUG packet_sent this=0x558134953d40, pn=1, ps=1352 client | 0.492 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG ECN probing: sent 1 probes client | 0.492 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG [Client edf16b735c773568] delay duration 105.112363ms client | 0.492 DEBUG Setting timeout of 105.112363ms client | 0.492 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 2 client | 0.492 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG [Client edf16b735c773568] delay duration 105.080623ms client | 0.492 DEBUG Setting timeout of 105.080623ms client | 0.492 DEBUG stream BiDi creatable client | 0.492 DEBUG stream UniDi creatable client | 0.492 WARN Unhandled event StateChange(Confirmed) client | 0.492 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG [Client edf16b735c773568] delay duration 105.060215ms client | 0.492 DEBUG Setting timeout of 105.060215ms client | 0.493 DEBUG [Client edf16b735c773568] pn=23 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 9, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [208, 79, 157, 33, 9, 67, 69, 238], stateless_reset_token: Token([244, 133, 250, 197, 134, 246, 236, 48, 95, 126, 155, 100, 252, 132, 158, 9]) } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [167, 82, 43, 85, 213, 86, 31, 28], stateless_reset_token: Token([232, 251, 220, 149, 117, 111, 99, 214, 227, 76, 113, 43, 38, 226, 72, 141]) } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [210, 72, 166, 35, 106, 210, 26, 110], stateless_reset_token: Token([163, 166, 21, 127, 40, 116, 17, 119, 10, 46, 4, 202, 193, 153, 105, 127]) } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [245, 234, 68, 219, 171, 46, 251, 192], stateless_reset_token: Token([118, 228, 250, 57, 197, 100, 56, 132, 213, 134, 37, 100, 255, 76, 83, 140]) } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [55, 204, 147, 84, 26, 173, 241, 133], stateless_reset_token: Token([7, 162, 197, 8, 67, 63, 230, 68, 221, 178, 113, 58, 204, 100, 187, 133]) } client | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [8, 64, 43, 95, 51, 219, 162, 76], stateless_reset_token: Token([110, 32, 171, 69, 37, 5, 230, 213, 183, 229, 17, 73, 141, 165, 191, 153]) } client | -> RX NewToken { token: [1, 113, 248, 16, 47, 197, 32, 30, 191, 36, 99, 117, 189, 60, 46, 215, 196, 74, 146, 149, 191, 144, 211, 172, 112, 107, 146, 172, 182, 13, 74, 218, 47, 170, 169, 68, 153, 118, 75, 197, 221, 238] } client | -> RX Stream { stream_id: 0, offset: 0, len: >>1015, fin: false } client | 0.493 DEBUG [Client edf16b735c773568] Rx ACK space=ap, ranges=[0..=0] client | 0.493 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=0 client | 0.493 DEBUG [Cubic [w_max: 12520, k: 1.9574338205844317, t_epoch: Some(Instant { tv_sec: 381, tv_nsec: 289086673 })] CongCtrl 1352/8764 ssthresh 8764] on_packets_acked this=0x558134953d40, limited=0, bytes_in_flight=1352, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 0.493 DEBUG resumption token [6151]: 0000000123405b00..2f7c1f83966b8294 client | 0.493 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 291883337 } client | 0.493 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.493 DEBUG [Client edf16b735c773568] pn=2 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 36 client | TX -> Ack { largest_acknowledged: 23, ack_delay: 12, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | 0.493 DEBUG ECN probing: sent 2 probes client | 0.493 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.493 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG [Client edf16b735c773568] delay duration 81.114827ms client | 0.493 DEBUG Setting timeout of 81.114827ms client | 0.493 DEBUG stream 0 complete client | 0.493 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.493 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG [Client edf16b735c773568] delay duration 81.088607ms client | 0.493 DEBUG Setting timeout of 81.088607ms client | 0.494 DEBUG [Client edf16b735c773568] pn=24 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Stream { stream_id: 0, offset: 1015, len: >>1230, fin: false } client | 0.494 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 312915576 } client | 0.494 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.494 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG [Client edf16b735c773568] delay duration 19.973841ms client | 0.494 DEBUG Setting timeout of 19.973841ms client | 0.494 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.494 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG [Client edf16b735c773568] delay duration 19.953593ms client | 0.494 DEBUG Setting timeout of 19.953593ms client | 0.495 DEBUG [Client edf16b735c773568] pn=25 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Stream { stream_id: 0, offset: 2245, len: >>1230, fin: false } client | 0.495 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 293939229 } client | 0.495 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.495 DEBUG [Client edf16b735c773568] pn=3 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 36 client | TX -> Ack { largest_acknowledged: 25, ack_delay: 3, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } client | 0.495 DEBUG ECN probing: sent 3 probes client | 0.495 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.495 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG [Client edf16b735c773568] delay duration 79.120754ms client | 0.495 DEBUG Setting timeout of 79.120754ms client | 0.495 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.495 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG [Client edf16b735c773568] delay duration 79.095637ms client | 0.495 DEBUG Setting timeout of 79.095637ms client | 0.496 DEBUG [Client edf16b735c773568] pn=26 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Stream { stream_id: 0, offset: 3475, len: >>1230, fin: false } client | 0.496 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 314958811 } client | 0.496 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.496 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG [Client edf16b735c773568] delay duration 19.976426ms client | 0.496 DEBUG Setting timeout of 19.976426ms client | 0.496 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.496 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG [Client edf16b735c773568] delay duration 19.942602ms client | 0.496 DEBUG Setting timeout of 19.942602ms client | 0.497 DEBUG [Client edf16b735c773568] pn=27 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 439 client | -> RX Stream { stream_id: 0, offset: 4705, len: 415, fin: true } client | 0.497 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 381, tv_nsec: 295331388 } client | 0.497 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.497 DEBUG [Client edf16b735c773568] pn=4 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 36 client | TX -> Ack { largest_acknowledged: 27, ack_delay: 2, first_ack_range: 4, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } client | 0.497 DEBUG ECN probing: sent 4 probes client | 0.497 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.497 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG [Client edf16b735c773568] delay duration 77.747047ms client | 0.497 DEBUG Setting timeout of 77.747047ms client | 0.497 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.497 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG [Client edf16b735c773568] delay duration 77.69554ms client | 0.497 DEBUG Setting timeout of 77.69554ms client | 0.497 DEBUG [Client edf16b735c773568] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 381, tv_nsec: 542039667 } } client | 0.497 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 381, tv_nsec: 542039667 } }) client | 0.497 DEBUG [Client edf16b735c773568] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG Building Short dcid Some(CID [8]: 91c1438a00d06830) client | 0.497 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 381, tv_nsec: 295503091 } client | 0.497 DEBUG [Client edf16b735c773568] pn=5 type=Short pri-path:91c1438a00d06830 0.0.0.0:38939->193.167.100.100:443 Tos(Cs0, Ect0) len 47 client | TX -> Ack { largest_acknowledged: 27, ack_delay: 21, first_ack_range: 4, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.497 DEBUG ECN probing: sent 5 probes client | 0.497 DEBUG Setting timeout of 246.483326ms client | 0.745 DEBUG [Client edf16b735c773568] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 381, tv_nsec: 542039667 } } -> Closed(Application(0)) client | 0.745 INFO Closing timer expired client | 0.745 DEBUG Output::None client | 0.745 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.745 DEBUG Timer fired while closed client | 0.745 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-12-13 11:38:13,777 Using the client's key log file. 2025-12-13 11:38:13,788 2025-12-13 11:38:13,789 Using the client's key log file. 2025-12-13 11:38:13,789 Using the client's key log file. 2025-12-13 11:38:13,799 2025-12-13 11:38:13,799 Using the client's key log file. 2025-12-13 11:38:13,799 Using the client's key log file. 2025-12-13 11:38:14,364 Check of downloaded files succeeded. 2025-12-13 11:38:14,665 Server sent 9967 bytes in Handshake CRYPTO frames. 2025-12-13 11:38:15,013 Received a 1252 byte Initial packet from the client. Amplification limit: 3756 2025-12-13 11:38:15,014 Received a 83 byte Handshake packet from the server. Total: 83 2025-12-13 11:38:15,014 Received a 1252 byte Initial packet from the client. Amplification limit: 7512 2025-12-13 11:38:15,014 Received a 40 byte Handshake packet from the server. Total: 123 2025-12-13 11:38:15,014 Received a 1252 byte Initial packet from the client. Amplification limit: 11268 2025-12-13 11:38:15,014 Received a 1252 byte Handshake packet from the server. Total: 1375 2025-12-13 11:38:15,014 Received a 1252 byte Handshake packet from the server. Total: 2627 2025-12-13 11:38:15,014 Received a 1252 byte Handshake packet from the server. Total: 3879 2025-12-13 11:38:15,014 Received a 1252 byte Handshake packet from the server. Total: 5131 2025-12-13 11:38:15,014 Received a 1252 byte Handshake packet from the server. Total: 6383 2025-12-13 11:38:15,014 Received a 1252 byte Handshake packet from the server. Total: 7635 2025-12-13 11:38:15,014 Received a 1252 byte Handshake packet from the server. Total: 8887 2025-12-13 11:38:15,014 Received a 1252 byte Handshake packet from the server. Total: 10139 2025-12-13 11:38:15,014 Received a 436 byte Handshake packet from the server. Total: 10575 2025-12-13 11:38:15,014 Received a 1252 byte Initial packet from the client. Amplification limit: 15024 2025-12-13 11:38:15,014 Received a 40 byte Handshake packet from the server. Total: 10615 2025-12-13 11:38:15,014 Received a 1252 byte Initial packet from the client. Amplification limit: 18780