2025-04-30 12:58:39,012 Generated random file: jgxlhzbyzc of size: 5120 2025-04-30 12:58:39,012 Requests: https://server4:443/jgxlhzbyzc 2025-04-30 12:58:39,428 2025-04-30 12:58:39,429 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_fzsntufl/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_n8ylyiji/ DOWNLOADS=/tmp/download__baqzcyk/ SERVER_LOGS=/tmp/logs_server_3_rr6jxz CLIENT_LOGS=/tmp/logs_client_e85ckpyj 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=stammw/quinn-interop:latest REQUESTS="https://server4:443/jgxlhzbyzc" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 12:58:58,988 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 server | Executing server server | 2025-04-30T12:58:40.030407Z INFO server: Listening on port 443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 client | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + '[' -n 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 1.00878526s 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/jgxlhzbyzc ']' 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/jgxlhzbyzc client | ' client | ++ tee -i -a /logs/client.log client | 0.000 DEBUG Logging initialized client | 0.000 DEBUG Default socket send buffer size is 212992 client | 0.001 DEBUG Default socket receive buffer size is 1048576, not changing client | 0.001 INFO hq-interop Client connecting: 0.0.0.0:43028 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=83124fc968a74cc8 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 client | 0.001 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 0.001 DEBUG [unv-path 0.0.0.0:43028->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:83124fc968a74cc8 0.0.0.0:43028->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:83124fc968a74cc8 0.0.0.0:43028->193.167.100.100:443] Path validated Instant { tv_sec: 850, tv_nsec: 71640464 } client | 0.001 DEBUG [Client 83124fc968a74cc8] client_start client | 0.001 DEBUG Writing transport parameters, msg=1 client | 0.001 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 0.001 DEBUG [Client 83124fc968a74cc8] State change from Init -> WaitInitial client | 0.001 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.001 DEBUG Building Initial dcid Some(CID [8]: 83124fc968a74cc8) scid Some(CID [0]: ) client | 0.001 DEBUG CRYPTO for in offset=0, len=1213 client | 0.001 DEBUG [Client 83124fc968a74cc8] pn=0 type=Initial pri-path:83124fc968a74cc8 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.001 DEBUG packet_sent this=0x556772bd5970, pn=0, ps=1252 client | 0.001 DEBUG ECN probing: sent 1 probes client | 0.002 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 83124fc968a74cc8) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=326 client | 0.002 DEBUG [Client 83124fc968a74cc8] pn=1 type=Initial pri-path:83124fc968a74cc8 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.002 DEBUG [Client 83124fc968a74cc8] pad Initial from 366 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x556772bd5970, pn=1, ps=1252 client | 0.002 DEBUG ECN probing: sent 2 probes client | 0.002 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 83124fc968a74cc8) 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 83124fc968a74cc8] delay duration 3.909626ms client | 0.002 DEBUG Setting timeout of 3.909626ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 83124fc968a74cc8) 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 83124fc968a74cc8] delay duration 3.886522ms client | 0.002 DEBUG Setting timeout of 3.886522ms client | 0.007 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [8]: 83124fc968a74cc8) 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 83124fc968a74cc8] delay duration 294.819899ms client | 0.007 DEBUG Setting timeout of 294.819899ms sim | Dropping packet 2 (1252 bytes) from 193.167.0.100 client | 0.039 DEBUG [Client 83124fc968a74cc8] pn=0 type=Initial pri-path:83124fc968a74cc8 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 43 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 13, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 0.039 DEBUG [Client 83124fc968a74cc8] Rx ACK space=in, ranges=[0..=0] client | 0.039 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 client | 0.039 DEBUG on_packets_acked this=0x556772bd5970, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252 client | 0.039 DEBUG Acked crypto frame space=in offset=0 length=1213 client | 0.039 DEBUG [Client 83124fc968a74cc8] Changing to use Server CID=2bec7bd572f6a8df client | 0.039 DEBUG [Client 83124fc968a74cc8] State change from WaitInitial -> WaitVersion client | 0.039 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG [Client 83124fc968a74cc8] delay duration 75.287339ms client | 0.039 DEBUG Setting timeout of 75.287339ms client | 0.039 WARN Unhandled event StateChange(WaitVersion) client | 0.039 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG [Client 83124fc968a74cc8] delay duration 75.262282ms client | 0.039 DEBUG Setting timeout of 75.262282ms client | 0.115 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.115 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 0.115 INFO Lost crypto frame space=in offset=1213 length=326 client | 0.115 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 0.115 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.115 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 0.115 DEBUG CRYPTO for in offset=1213, len=326 client | 0.115 DEBUG [Client 83124fc968a74cc8] pn=2 type=Initial pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.115 DEBUG [Client 83124fc968a74cc8] pad Initial from 366 to PLPMTU 1252 client | 0.115 DEBUG packet_sent this=0x556772bd5970, pn=2, ps=1252 client | 0.115 DEBUG ECN probing: sent 3 probes client | 0.115 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG [Client 83124fc968a74cc8] delay duration 224.565434ms client | 0.115 DEBUG Setting timeout of 224.565434ms sim | Dropping packet 3 (1252 bytes) from 193.167.0.100 client | 0.341 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.341 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 0.341 INFO Lost crypto frame space=in offset=1213 length=326 client | 0.341 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 0.341 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.341 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 0.341 DEBUG CRYPTO for in offset=1213, len=326 client | 0.341 DEBUG [Client 83124fc968a74cc8] pn=3 type=Initial pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.341 DEBUG [Client 83124fc968a74cc8] pad Initial from 366 to PLPMTU 1252 client | 0.341 DEBUG packet_sent this=0x556772bd5970, pn=3, ps=1252 client | 0.341 DEBUG ECN probing: sent 4 probes client | 0.341 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.341 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 0.341 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.341 DEBUG [Client 83124fc968a74cc8] delay duration 449.327278ms client | 0.341 DEBUG Setting timeout of 449.327278ms sim | Dropping packet 4 (1252 bytes) from 193.167.0.100 client | 0.791 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.791 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 0.791 INFO Lost crypto frame space=in offset=1213 length=326 client | 0.791 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 0.791 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.791 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 0.791 DEBUG CRYPTO for in offset=1213, len=326 client | 0.791 DEBUG [Client 83124fc968a74cc8] pn=4 type=Initial pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.791 DEBUG [Client 83124fc968a74cc8] pad Initial from 366 to PLPMTU 1252 client | 0.791 DEBUG packet_sent this=0x556772bd5970, pn=4, ps=1252 client | 0.791 DEBUG ECN probing: sent 5 probes client | 0.792 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.792 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 0.792 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.792 DEBUG [Client 83124fc968a74cc8] delay duration 898.82715ms client | 0.792 DEBUG Setting timeout of 898.82715ms sim | Dropping packet 5 (1252 bytes) from 193.167.0.100 client | 1.692 DEBUG [LossRecovery] PTO timer fired for Initial client | 1.692 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 1.692 INFO Lost crypto frame space=in offset=1213 length=326 client | 1.692 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 1.692 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 1.692 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 1.692 DEBUG CRYPTO for in offset=1213, len=326 client | 1.692 DEBUG [Client 83124fc968a74cc8] pn=5 type=Initial pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 1.692 DEBUG [Client 83124fc968a74cc8] pad Initial from 366 to PLPMTU 1252 client | 1.692 DEBUG packet_sent this=0x556772bd5970, pn=5, ps=1252 client | 1.692 DEBUG ECN probing: sent 6 probes client | 1.692 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.692 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 1.692 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.692 DEBUG [Client 83124fc968a74cc8] delay duration 1.797885865s client | 1.692 DEBUG Setting timeout of 1.797885865s sim | Dropping packet 6 (1252 bytes) from 193.167.0.100 client | 3.491 DEBUG [LossRecovery] PTO timer fired for Initial client | 3.491 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 3.491 INFO Lost crypto frame space=in offset=1213 length=326 client | 3.491 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 3.491 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 3.491 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 3.491 DEBUG CRYPTO for in offset=1213, len=326 client | 3.491 DEBUG [Client 83124fc968a74cc8] pn=6 type=Initial pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 3.491 DEBUG [Client 83124fc968a74cc8] pad Initial from 366 to PLPMTU 1252 client | 3.491 DEBUG packet_sent this=0x556772bd5970, pn=6, ps=1252 client | 3.491 DEBUG ECN probing: sent 7 probes client | 3.491 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.491 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 3.491 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.491 DEBUG [Client 83124fc968a74cc8] delay duration 3.595925498s client | 3.491 DEBUG Setting timeout of 3.595925498s sim | Dropping packet 7 (1252 bytes) from 193.167.0.100 client | 7.088 DEBUG [LossRecovery] PTO timer fired for Initial client | 7.088 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 7.088 INFO Lost crypto frame space=in offset=1213 length=326 client | 7.088 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 7.088 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 7.088 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.088 DEBUG CRYPTO for in offset=1213, len=326 client | 7.088 DEBUG [Client 83124fc968a74cc8] pn=7 type=Initial pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 7.088 DEBUG [Client 83124fc968a74cc8] pad Initial from 366 to PLPMTU 1252 client | 7.088 DEBUG packet_sent this=0x556772bd5970, pn=7, ps=1252 client | 7.088 DEBUG ECN probing: sent 8 probes client | 7.088 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.088 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.088 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.088 DEBUG [Client 83124fc968a74cc8] delay duration 7.192107417s client | 7.088 DEBUG Setting timeout of 7.192107417s client | 7.122 DEBUG [Client 83124fc968a74cc8] pn=1 type=Initial pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 139 client | -> RX Ack { largest_acknowledged: 7, ack_delay: 53, first_ack_range: 0, ack_ranges: [AckRange { gap: 5, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | -> RX Crypto { offset: 0, len: 90 } client | 7.122 DEBUG [Client 83124fc968a74cc8] Rx ACK space=in, ranges=[7..=7, 0..=0] client | 7.122 DEBUG [LossRecovery] ACK for Initial - largest_acked=7 client | 7.122 DEBUG packet_lost this=0x556772bd5970, pn=1, ps=1252 client | 7.122 DEBUG packet_lost this=0x556772bd5970, pn=2, ps=1252 client | 7.122 DEBUG packet_lost this=0x556772bd5970, pn=3, ps=1252 client | 7.122 DEBUG packet_lost this=0x556772bd5970, pn=4, ps=1252 client | 7.122 DEBUG packet_lost this=0x556772bd5970, pn=5, ps=1252 client | 7.122 DEBUG packet_lost this=0x556772bd5970, pn=6, ps=1252 client | 7.122 DEBUG [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.122 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1252/8764 ssthresh 8764] state -> RecoveryStart client | 7.122 INFO [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1252/8764 ssthresh 8764] persistent congestion client | 7.122 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1252/2504 ssthresh 8764] state -> PersistentCongestion client | 7.122 DEBUG on_packets_lost this=0x556772bd5970, bytes_in_flight=1252, cwnd=2504, state=PersistentCongestion client | 7.122 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.122 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/2504 ssthresh 8764] on_packets_acked this=0x556772bd5970, limited=0, bytes_in_flight=0, cwnd=2504, state=PersistentCongestion, new_acked=0 client | 7.122 DEBUG Acked crypto frame space=in offset=1213 length=326 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 7.122 INFO Lost crypto frame space=in offset=1213 length=326 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 7.122 INFO Lost crypto frame space=in offset=1213 length=326 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 7.122 INFO Lost crypto frame space=in offset=1213 length=326 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 7.122 INFO Lost crypto frame space=in offset=1213 length=326 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 7.122 INFO Lost crypto frame space=in offset=1213 length=326 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 7.122 INFO Lost crypto frame space=in offset=1213 length=326 client | 7.122 DEBUG [Client 83124fc968a74cc8] Lost: EcnEct0 client | 7.122 DEBUG Read Ok(90) bytes client | 7.122 DEBUG Write secret available for Handshake: SymKey [32]: 031d6f278b070ec6bcfdd5467b6f24b12889f0202f25efff5981ec7cc3271a41 client | 7.122 DEBUG Read secret available for Handshake: SymKey [32]: ab66ccd3c100326ac0ebd74d7edc41491c05c0062f17d868e6172bcdd8a3a44a client | 7.122 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 7.122 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 7.122 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 7.122 DEBUG [Crypto] Handshake keys installed client | 7.122 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 192911749 } client | 7.122 DEBUG [Client 83124fc968a74cc8] State change from WaitVersion -> Handshaking client | 7.123 DEBUG [Client 83124fc968a74cc8] pn=0 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1313 client | -> RX Crypto { offset: 0, len: 1275 } client | 7.123 DEBUG Read Ok(1275) bytes client | 7.123 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 7.123 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 192911749 } client | 7.123 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG Building Initial dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.123 DEBUG [Client 83124fc968a74cc8] pn=8 type=Initial pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 44 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 97, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | 7.123 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.123 DEBUG [Client 83124fc968a74cc8] pn=0 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 87 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 97, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 7.123 DEBUG [Client 83124fc968a74cc8] Drop packet number space in client | 7.123 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 7.123 WARN [LossRecovery] ignoring in-8 from dropped space client | 7.123 DEBUG ECN probing: sent 9 probes client | 7.123 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.123 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG [Client 83124fc968a74cc8] delay duration 96.913495ms client | 7.123 DEBUG Setting timeout of 96.913495ms client | 7.123 DEBUG StateChange(Handshaking) client | 7.123 WARN Cannot create stream ConnectionState client | 7.123 DEBUG stream BiDi creatable client | 7.123 WARN Cannot create stream ConnectionState client | 7.123 DEBUG stream UniDi creatable client | 7.123 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.123 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG [Client 83124fc968a74cc8] delay duration 96.876215ms client | 7.123 DEBUG Setting timeout of 96.876215ms client | 7.123 DEBUG [Client 83124fc968a74cc8] pn=1 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Crypto { offset: 1275, len: 1413 } client | 7.123 DEBUG Read Ok(1413) bytes client | 7.123 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 7.123 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 193965145 } client | 7.123 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.123 DEBUG [Client 83124fc968a74cc8] pn=1 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 42 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 9, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | 7.123 DEBUG ECN probing: sent 10 probes client | 7.123 DEBUG ECN probing concluded with 10 probes sent client | 7.123 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.123 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG [Client 83124fc968a74cc8] delay duration 96.61634ms client | 7.123 DEBUG Setting timeout of 96.61634ms client | 7.123 DEBUG stream BiDi creatable client | 7.123 WARN Cannot create stream ConnectionState client | 7.123 DEBUG stream UniDi creatable client | 7.123 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.123 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.123 DEBUG [Client 83124fc968a74cc8] delay duration 96.590612ms client | 7.123 DEBUG Setting timeout of 96.590612ms client | 7.124 DEBUG [Client 83124fc968a74cc8] pn=2 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Crypto { offset: 2688, len: 1413 } client | 7.124 DEBUG Read Ok(1413) bytes client | 7.124 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 7.124 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 195084881 } client | 7.124 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.124 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.124 DEBUG [Client 83124fc968a74cc8] pn=2 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 4, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } client | 7.124 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.124 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.124 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.124 DEBUG [Client 83124fc968a74cc8] delay duration 95.54635ms client | 7.124 DEBUG Setting timeout of 95.54635ms client | 7.124 DEBUG stream BiDi creatable client | 7.124 WARN Cannot create stream ConnectionState client | 7.124 DEBUG stream UniDi creatable client | 7.124 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.124 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.124 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.124 DEBUG [Client 83124fc968a74cc8] delay duration 95.517596ms client | 7.124 DEBUG Setting timeout of 95.517596ms client | 7.125 DEBUG [Client 83124fc968a74cc8] pn=3 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Crypto { offset: 4101, len: 1413 } client | 7.125 DEBUG Read Ok(1413) bytes client | 7.125 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 7.125 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 196247532 } client | 7.125 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.125 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.125 DEBUG [Client 83124fc968a74cc8] pn=3 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 3, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } client | 7.125 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.125 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.125 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.125 DEBUG [Client 83124fc968a74cc8] delay duration 94.372025ms client | 7.125 DEBUG Setting timeout of 94.372025ms client | 7.125 DEBUG stream BiDi creatable client | 7.125 WARN Cannot create stream ConnectionState client | 7.125 DEBUG stream UniDi creatable client | 7.125 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.125 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.125 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.125 DEBUG [Client 83124fc968a74cc8] delay duration 94.34254ms client | 7.125 DEBUG Setting timeout of 94.34254ms client | 7.126 DEBUG [Client 83124fc968a74cc8] pn=4 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Crypto { offset: 5514, len: 1413 } client | 7.126 DEBUG Read Ok(1413) bytes client | 7.126 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 7.126 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 197454629 } client | 7.126 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.126 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.126 DEBUG [Client 83124fc968a74cc8] pn=4 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 5, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } client | 7.126 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.126 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.126 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.126 DEBUG [Client 83124fc968a74cc8] delay duration 93.176019ms client | 7.126 DEBUG Setting timeout of 93.176019ms client | 7.126 DEBUG stream BiDi creatable client | 7.126 WARN Cannot create stream ConnectionState client | 7.126 DEBUG stream UniDi creatable client | 7.126 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.126 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.126 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.126 DEBUG [Client 83124fc968a74cc8] delay duration 93.15021ms client | 7.126 DEBUG Setting timeout of 93.15021ms client | 7.128 DEBUG [Client 83124fc968a74cc8] pn=5 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Crypto { offset: 6927, len: 1413 } client | 7.128 DEBUG Read Ok(1413) bytes client | 7.128 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 7.128 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 198644483 } client | 7.128 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.128 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.128 DEBUG [Client 83124fc968a74cc8] pn=5 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } client | 7.128 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.128 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.128 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.128 DEBUG [Client 83124fc968a74cc8] delay duration 92.020548ms client | 7.128 DEBUG Setting timeout of 92.020548ms client | 7.128 DEBUG stream BiDi creatable client | 7.128 WARN Cannot create stream ConnectionState client | 7.128 DEBUG stream UniDi creatable client | 7.128 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.128 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.128 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.128 DEBUG [Client 83124fc968a74cc8] delay duration 92.00545ms client | 7.128 DEBUG Setting timeout of 92.00545ms client | 7.155 DEBUG [Client 83124fc968a74cc8] pn=6 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Crypto { offset: 8340, len: 1413 } client | 7.155 DEBUG Read Ok(1413) bytes client | 7.155 DEBUG [Agent 0x556772bc2270] state -> InProgress client | 7.155 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 225954906 } client | 7.155 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.155 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.155 DEBUG [Client 83124fc968a74cc8] pn=6 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 13, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } client | 7.155 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.155 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.155 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.155 DEBUG [Client 83124fc968a74cc8] delay duration 64.546418ms client | 7.155 DEBUG Setting timeout of 64.546418ms client | 7.155 DEBUG [Client 83124fc968a74cc8] pn=7 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 257 client | -> RX Crypto { offset: 9753, len: 218 } client | 7.155 DEBUG Read Ok(218) bytes client | 7.156 DEBUG [Agent 0x556772bc2270] state -> AuthenticationPending client | 7.156 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 226222579 } client | 7.156 DEBUG saving datagram of 214 bytes client | 7.156 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.156 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.156 DEBUG [Client 83124fc968a74cc8] pn=7 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 94, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } client | 7.156 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.156 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.156 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.156 DEBUG [Client 83124fc968a74cc8] delay duration 63.673609ms client | 7.156 DEBUG Setting timeout of 63.673609ms client | 7.156 DEBUG stream BiDi creatable client | 7.156 WARN Cannot create stream ConnectionState client | 7.156 DEBUG stream UniDi creatable client | 7.156 DEBUG [Client 83124fc968a74cc8] Authenticated Ok client | 7.156 DEBUG Write secret available for ApplicationData: SymKey [32]: f278e9bb10e8607cfc4fe2a4a44f1ad734432b7b1791817893356db9a6a36c47 client | 7.156 DEBUG Read secret available for ApplicationData: SymKey [32]: e74ebaa97392e1981f21b504296adf7c2544a2e04b1bcb559ac79f31e9f08ede client | 7.156 DEBUG [Agent 0x556772bc2270] SSL_AuthCertificateComplete: Ok(()) client | 7.156 DEBUG [Agent 0x556772bc2270] 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 | 7.156 DEBUG [Client 83124fc968a74cc8] TLS connection complete client | 7.156 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 7.156 DEBUG [Crypto] Application write key installed client | 7.156 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 7.156 DEBUG [Crypto] application read keys installed client | 7.156 DEBUG [Client 83124fc968a74cc8] State change from Handshaking -> Connected client | 7.156 INFO [Client 83124fc968a74cc8] Connection established client | 7.156 DEBUG [Client 83124fc968a74cc8] process saved for epoch ApplicationData client | 7.156 DEBUG [Client 83124fc968a74cc8] pn=0 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 214 client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [40, 106, 6, 205, 161, 59, 42, 101], stateless_reset_token: [213, 166, 212, 199, 57, 87, 249, 12, 170, 107, 208, 38, 80, 196, 17, 82] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [238, 116, 144, 187, 250, 5, 97, 190], stateless_reset_token: [206, 238, 145, 255, 231, 56, 157, 191, 123, 71, 146, 243, 65, 153, 25, 61] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [83, 166, 114, 204, 149, 167, 248, 31], stateless_reset_token: [62, 245, 228, 212, 168, 254, 247, 140, 130, 93, 28, 120, 142, 183, 169, 19] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [162, 69, 65, 18, 35, 160, 14, 159], stateless_reset_token: [106, 161, 211, 201, 252, 193, 87, 16, 248, 238, 157, 102, 187, 72, 80, 240] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [80, 215, 182, 100, 96, 29, 76, 152], stateless_reset_token: [252, 183, 57, 23, 233, 57, 175, 246, 4, 66, 198, 34, 182, 66, 200, 201] } client | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [207, 152, 3, 156, 168, 253, 198, 205], stateless_reset_token: [36, 138, 198, 27, 45, 118, 49, 7, 38, 9, 36, 196, 121, 214, 74, 215] } client | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [165, 68, 180, 156, 49, 32, 129, 185], stateless_reset_token: [197, 226, 218, 153, 131, 206, 75, 31, 223, 125, 94, 237, 245, 235, 162, 85] } client | 7.156 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 246222579 } client | 7.156 DEBUG stream BiDi creatable client | 7.156 INFO Created stream 0 for https://server4/jgxlhzbyzc client | 7.156 INFO Saving https://server4/jgxlhzbyzc to "/downloads/jgxlhzbyzc" client | 7.156 DEBUG stream UniDi creatable client | 7.156 DEBUG StateChange(Connected) client | 7.156 DEBUG stream 0 writable client | 7.156 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.157 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.157 DEBUG CRYPTO for hs offset=0, len=36 client | 7.157 DEBUG [Client 83124fc968a74cc8] pn=8 type=Handshake pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 7.157 DEBUG packet_sent this=0x556772bd5970, pn=8, ps=73 client | 7.157 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.157 DEBUG [Client 83124fc968a74cc8] pn=0 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 119 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 7.157 DEBUG packet_sent this=0x556772bd5970, pn=0, ps=46 client | 7.157 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.157 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.157 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.157 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.157 DEBUG [Client 83124fc968a74cc8] delay duration 18.48254ms client | 7.157 DEBUG Setting timeout of 18.48254ms server | 2025-04-30T12:58:48.236901Z INFO server: serving /www/jgxlhzbyzc client | 7.176 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.176 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.176 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.176 DEBUG [Client 83124fc968a74cc8] pn=1 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 35 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 2593, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 7.176 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.176 DEBUG Building Handshake dcid Some(CID [8]: 2bec7bd572f6a8df) scid Some(CID [0]: ) client | 7.176 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.176 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.176 DEBUG [Client 83124fc968a74cc8] delay duration 77.501573ms client | 7.176 DEBUG Setting timeout of 77.501573ms client | 7.188 DEBUG [Client 83124fc968a74cc8] pn=1 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 379 client | -> RX HandshakeDone client | -> RX Crypto { offset: 0, len: 356 } client | 7.188 DEBUG [Client 83124fc968a74cc8] State change from Connected -> Confirmed client | 7.188 DEBUG PMTUD started with probe size 1380 client | 7.188 DEBUG [Client 83124fc968a74cc8] Drop packet number space hs client | 7.188 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 7.188 DEBUG [Client 83124fc968a74cc8] No preferred address to migrate to client | 7.188 DEBUG Read Ok(356) bytes client | 7.188 DEBUG [0x556772bc2270] Got resumption token [5786]: 02000633fe7a628c..5238552cf47872b9 client | 7.188 DEBUG [0x556772bc2270] Got resumption token [5786]: 02000633fe7a628c..82fe6dea1c1aa01f client | 7.188 DEBUG [0x556772bc2270] Got resumption token [5786]: 02000633fe7a628c..f7f24dcd791a8689 client | 7.188 DEBUG [0x556772bc2270] Got resumption token [5786]: 02000633fe7a628c..4e4bf3518fb6d9ff client | 7.188 DEBUG [Agent 0x556772bc2270] 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 | 7.188 DEBUG setting max_stream_data to 1250000 client | 7.188 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 278787889 } client | 7.188 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.188 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.188 DEBUG Sending PMTUD probe of size 1380, count 1 client | 7.188 DEBUG [Client 83124fc968a74cc8] pn=2 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 1352 client | TX -> Ping client | TX -> Padding { len: 1325 } client | 7.188 DEBUG packet_sent this=0x556772bd5970, pn=2, ps=1352 client | 7.188 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.188 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.188 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.188 DEBUG [Client 83124fc968a74cc8] delay duration 19.363734ms client | 7.188 DEBUG Setting timeout of 19.363734ms client | 7.188 WARN Unhandled event StateChange(Confirmed) client | 7.188 DEBUG stream BiDi creatable client | 7.188 DEBUG stream UniDi creatable client | 7.188 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.188 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.188 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.188 DEBUG [Client 83124fc968a74cc8] delay duration 19.311917ms client | 7.188 DEBUG Setting timeout of 19.311917ms client | 7.189 DEBUG [Client 83124fc968a74cc8] pn=2 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Stream { stream_id: 0, offset: 0, len: >>1432, fin: false } client | 7.189 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 259852789 } client | 7.189 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.189 DEBUG [Client 83124fc968a74cc8] pn=3 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 8, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } client | 7.189 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.189 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG [Client 83124fc968a74cc8] delay duration 121.196747ms client | 7.189 DEBUG Setting timeout of 121.196747ms client | 7.189 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.189 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG [Client 83124fc968a74cc8] delay duration 121.164457ms client | 7.189 DEBUG Setting timeout of 121.164457ms client | 7.190 DEBUG [Client 83124fc968a74cc8] pn=3 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Stream { stream_id: 0, offset: 1432, len: >>1430, fin: false } client | 7.190 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 280972614 } client | 7.190 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.190 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG [Client 83124fc968a74cc8] delay duration 19.976325ms client | 7.190 DEBUG Setting timeout of 19.976325ms client | 7.190 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.190 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG [Client 83124fc968a74cc8] delay duration 19.936941ms client | 7.190 DEBUG Setting timeout of 19.936941ms client | 7.191 DEBUG [Client 83124fc968a74cc8] pn=4 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 1452 client | -> RX Stream { stream_id: 0, offset: 2862, len: >>1430, fin: false } client | 7.191 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 262169693 } client | 7.191 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.191 DEBUG [Client 83124fc968a74cc8] pn=4 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 2, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } client | 7.191 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.191 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG [Client 83124fc968a74cc8] delay duration 118.972642ms client | 7.191 DEBUG Setting timeout of 118.972642ms client | 7.191 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.191 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG [Client 83124fc968a74cc8] delay duration 118.945521ms client | 7.191 DEBUG Setting timeout of 118.945521ms client | 7.192 DEBUG [Client 83124fc968a74cc8] pn=5 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 852 client | -> RX Stream { stream_id: 0, offset: 4292, len: 828, fin: false } client | 7.192 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 282868976 } client | 7.192 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.192 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG [Client 83124fc968a74cc8] delay duration 19.971486ms client | 7.192 DEBUG Setting timeout of 19.971486ms client | 7.192 DEBUG [Client 83124fc968a74cc8] pn=6 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, Ect0) len 23 client | -> RX Stream { stream_id: 0, offset: 5120, len: 0, fin: true } client | 7.192 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 857, tv_nsec: 262914211 } client | 7.192 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.192 DEBUG [Client 83124fc968a74cc8] pn=5 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 2, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } client | 7.192 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.192 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG [Client 83124fc968a74cc8] delay duration 118.242158ms client | 7.192 DEBUG Setting timeout of 118.242158ms client | 7.192 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.192 DEBUG TX blocked, profile=SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG [Client 83124fc968a74cc8] delay duration 118.160255ms client | 7.192 DEBUG Setting timeout of 118.160255ms client | 7.192 DEBUG [Client 83124fc968a74cc8] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 857, tv_nsec: 629226971 } } client | 7.192 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 857, tv_nsec: 629226971 } }) client | 7.192 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.192 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 857, tv_nsec: 263103597 } client | 7.192 DEBUG [Client 83124fc968a74cc8] pn=6 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 45 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 23, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.192 DEBUG Setting timeout of 366.067109ms client | 7.214 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.214 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.214 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 857, tv_nsec: 285055218 } client | 7.214 DEBUG [Client 83124fc968a74cc8] pn=7 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 46 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 2767, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.214 DEBUG Setting timeout of 343.965245ms client | 7.214 DEBUG Setting timeout of 343.949696ms client | 7.223 DEBUG [Client 83124fc968a74cc8] output_path send_profile SendProfile { limit: 1106, pto: None, probe: EnumSet(), paced: false } client | 7.223 DEBUG Building Short dcid Some(CID [8]: 2bec7bd572f6a8df) client | 7.223 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 857, tv_nsec: 293755589 } client | 7.223 DEBUG [Client 83124fc968a74cc8] pn=8 type=Short pri-path:2bec7bd572f6a8df 0.0.0.0:43028->193.167.100.100:443 IpTos(Cs0, NotEct) len 46 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 3855, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.223 DEBUG Setting timeout of 335.33209ms client | 7.223 DEBUG Setting timeout of 335.318725ms client | 7.559 DEBUG [Client 83124fc968a74cc8] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 857, tv_nsec: 629226971 } } -> Closed(Application(0)) client | 7.559 INFO Closing timer expired client | 7.559 DEBUG Output::None client | 7.559 WARN Unhandled event StateChange(Closed(Application(0))) client | 7.559 DEBUG Timer fired while closed client | 7.559 DEBUG Output::None client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 12:58:59,111 Using the client's key log file. 2025-04-30 12:58:59,124 2025-04-30 12:58:59,125 Using the client's key log file. 2025-04-30 12:58:59,125 Using the client's key log file. 2025-04-30 12:58:59,139 2025-04-30 12:58:59,139 Using the client's key log file. 2025-04-30 12:58:59,139 Using the client's key log file. 2025-04-30 12:58:59,841 Check of downloaded files succeeded. 2025-04-30 12:59:00,193 Server sent 9971 bytes in Handshake CRYPTO frames. 2025-04-30 12:59:00,591 Received a 1252 byte Initial packet from the client. Amplification limit: 3756 2025-04-30 12:59:00,591 Received a 43 byte Handshake packet from the server. Total: 43 2025-04-30 12:59:00,591 Received a 1252 byte Initial packet from the client. Amplification limit: 7512 2025-04-30 12:59:00,591 Received a 1452 byte Handshake packet from the server. Total: 1495 2025-04-30 12:59:00,591 Received a 1452 byte Handshake packet from the server. Total: 2947 2025-04-30 12:59:00,591 Received a 1452 byte Handshake packet from the server. Total: 4399 2025-04-30 12:59:00,591 Received a 1452 byte Handshake packet from the server. Total: 5851 2025-04-30 12:59:00,591 Received a 1452 byte Handshake packet from the server. Total: 7303 2025-04-30 12:59:00,591 Received a 1452 byte Handshake packet from the server. Total: 8755 2025-04-30 12:59:00,591 Server violated the amplification limit, but stayed within 3-4x amplification. Letting it slide. 2025-04-30 12:59:00,591 Received a 87 byte Initial packet from the client. Amplification limit: 7773