2025-09-11 21:24:06,075 Generated random file: ghnydsfcpr of size: 5120 2025-09-11 21:24:06,075 Requests: https://server4:443/ghnydsfcpr 2025-09-11 21:24:06,461 2025-09-11 21:24:06,461 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_bbj7tj3l/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_3b7mc1k9/ DOWNLOADS=/tmp/download_edej4a5d/ SERVER_LOGS=/tmp/logs_server_ro_a8zps CLIENT_LOGS=/tmp/logs_client_dsdrcr7w 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=ghcr.io/alibaba/xquic/xquic-interop:latest REQUESTS="https://server4:443/ghnydsfcpr" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-11 21:24:25,941 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server: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 server | Setting up routes... client | Endpoint's IPv6 address is fd00:cafe:cafe::100 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 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 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | ./demo_server -l d -L /logs/server.log -p 443 -D /www -k /logs/keys.log -i -M server | option log level :d server | option log directory :/logs/server.log server | option port :443 server | option read dir :/www server | option key output file: /logs/keys.log server | set interop mode server | option multipath enabled server | /logs/keys.log 4 server | create ipv4 socket fd: 9 server | create ipv6 socket fd: 10 sim | server:443 is available after 501.636598ms sim | Using scenario: droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7 sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads) client | + '[' https://server4:443/ghnydsfcpr ']' 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/ghnydsfcpr 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:60765 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=1e2577d4893abe907f7706fe8c client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=15 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:60765->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:1e2577d4893abe907f7706fe8c 0.0.0.0:60765->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:1e2577d4893abe907f7706fe8c 0.0.0.0:60765->193.167.100.100:443] Path validated Instant { tv_sec: 620, tv_nsec: 337765083 } client | 0.001 DEBUG [Client 1e2577d4893abe907f7706fe8c] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 0.002 DEBUG [Client 1e2577d4893abe907f7706fe8c] State change from Init -> WaitInitial client | 0.002 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: 1e2577d4893abe907f7706fe8c) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1208 client | 0.002 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=15 type=Initial pri-path:1e2577d4893abe907f7706fe8c 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 0.002 DEBUG packet_sent this=0x55f8641c1ad0, pn=15, ps=1252 client | 0.002 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: 1e2577d4893abe907f7706fe8c) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1208, len=332 client | 0.002 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=16 type=Initial pri-path:1e2577d4893abe907f7706fe8c 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 377 client | TX -> Crypto { offset: 1208, len: 332 } client | 0.002 DEBUG [Client 1e2577d4893abe907f7706fe8c] pad Initial from 377 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55f8641c1ad0, pn=16, ps=1252 client | 0.002 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: 1e2577d4893abe907f7706fe8c) 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 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: 1e2577d4893abe907f7706fe8c) 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 1e2577d4893abe907f7706fe8c] delay duration 3.814543ms client | 0.002 DEBUG Setting timeout of 3.814543ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: 1e2577d4893abe907f7706fe8c) 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 1e2577d4893abe907f7706fe8c] delay duration 3.77568ms client | 0.002 DEBUG Setting timeout of 3.77568ms client | 0.007 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [13]: 1e2577d4893abe907f7706fe8c) 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 1e2577d4893abe907f7706fe8c] delay duration 294.287674ms client | 0.007 DEBUG Setting timeout of 294.287674ms sim | Dropping packet 2 (1252 bytes) from 193.167.0.100 client | 0.039 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=0 type=Initial pri-path:1e2577d4893abe907f7706fe8c 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 48 client | -> RX Ack { largest_acknowledged: 15, ack_delay: 75, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.039 DEBUG [Client 1e2577d4893abe907f7706fe8c] Rx ACK space=in, ranges=[15..=15] client | 0.039 DEBUG [recovery::Loss] ACK for Initial - largest_acked=15 client | 0.039 DEBUG on_packets_acked this=0x55f8641c1ad0, 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=1208 client | 0.039 DEBUG [Client 1e2577d4893abe907f7706fe8c] Changing to use Server CID=a10fea16ffe854423c5a605a client | 0.039 DEBUG [Client 1e2577d4893abe907f7706fe8c] State change from WaitInitial -> WaitVersion client | 0.039 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) 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 1e2577d4893abe907f7706fe8c] delay duration 75.522272ms client | 0.039 DEBUG Setting timeout of 75.522272ms client | 0.039 WARN Unhandled event StateChange(WaitVersion) client | 0.039 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) 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 1e2577d4893abe907f7706fe8c] delay duration 75.485564ms client | 0.039 DEBUG Setting timeout of 75.485564ms client | 0.115 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.115 DEBUG [Client 1e2577d4893abe907f7706fe8c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 332 }) client | 0.115 INFO Lost crypto frame space=in offset=1208 length=332 client | 0.115 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.115 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.115 DEBUG CRYPTO for in offset=1208, len=332 client | 0.115 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=17 type=Initial pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 376 client | TX -> Crypto { offset: 1208, len: 332 } client | 0.115 DEBUG [Client 1e2577d4893abe907f7706fe8c] pad Initial from 376 to PLPMTU 1252 client | 0.115 DEBUG packet_sent this=0x55f8641c1ad0, pn=17, ps=1252 client | 0.115 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.116 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 226.667876ms client | 0.116 DEBUG Setting timeout of 226.667876ms sim | Dropping packet 3 (1252 bytes) from 193.167.0.100 client | 0.344 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.344 DEBUG [Client 1e2577d4893abe907f7706fe8c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 332 }) client | 0.344 INFO Lost crypto frame space=in offset=1208 length=332 client | 0.344 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.344 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.344 DEBUG CRYPTO for in offset=1208, len=332 client | 0.344 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=18 type=Initial pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 376 client | TX -> Crypto { offset: 1208, len: 332 } client | 0.344 DEBUG [Client 1e2577d4893abe907f7706fe8c] pad Initial from 376 to PLPMTU 1252 client | 0.344 DEBUG packet_sent this=0x55f8641c1ad0, pn=18, ps=1252 client | 0.344 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.344 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.344 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.344 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.344 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.344 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.344 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 453.523982ms client | 0.344 DEBUG Setting timeout of 453.523982ms sim | Dropping packet 4 (1252 bytes) from 193.167.0.100 client | 0.799 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.799 DEBUG [Client 1e2577d4893abe907f7706fe8c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 332 }) client | 0.799 INFO Lost crypto frame space=in offset=1208 length=332 client | 0.799 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.799 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.799 DEBUG CRYPTO for in offset=1208, len=332 client | 0.799 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=19 type=Initial pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 376 client | TX -> Crypto { offset: 1208, len: 332 } client | 0.799 DEBUG [Client 1e2577d4893abe907f7706fe8c] pad Initial from 376 to PLPMTU 1252 client | 0.799 DEBUG packet_sent this=0x55f8641c1ad0, pn=19, ps=1252 client | 0.799 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.799 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.799 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.799 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.799 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 0.799 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.799 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 907.285646ms client | 0.799 DEBUG Setting timeout of 907.285646ms sim | Dropping packet 5 (1252 bytes) from 193.167.0.100 client | 1.707 DEBUG [recovery::Loss] PTO timer fired for Initial client | 1.707 DEBUG [Client 1e2577d4893abe907f7706fe8c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 332 }) client | 1.707 INFO Lost crypto frame space=in offset=1208 length=332 client | 1.707 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 1.707 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 1.707 DEBUG CRYPTO for in offset=1208, len=332 client | 1.707 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=20 type=Initial pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 376 client | TX -> Crypto { offset: 1208, len: 332 } client | 1.707 DEBUG [Client 1e2577d4893abe907f7706fe8c] pad Initial from 376 to PLPMTU 1252 client | 1.707 DEBUG packet_sent this=0x55f8641c1ad0, pn=20, ps=1252 client | 1.707 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 1.707 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 1.707 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 1.707 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.707 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 1.707 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.707 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 1.814750715s client | 1.707 DEBUG Setting timeout of 1.814750715s sim | Dropping packet 6 (1252 bytes) from 193.167.0.100 client | 3.523 DEBUG [recovery::Loss] PTO timer fired for Initial client | 3.523 DEBUG [Client 1e2577d4893abe907f7706fe8c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 332 }) client | 3.523 INFO Lost crypto frame space=in offset=1208 length=332 client | 3.523 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 3.523 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 3.523 DEBUG CRYPTO for in offset=1208, len=332 client | 3.523 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=21 type=Initial pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 376 client | TX -> Crypto { offset: 1208, len: 332 } client | 3.523 DEBUG [Client 1e2577d4893abe907f7706fe8c] pad Initial from 376 to PLPMTU 1252 client | 3.523 DEBUG packet_sent this=0x55f8641c1ad0, pn=21, ps=1252 client | 3.523 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 3.523 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 3.523 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 3.523 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.523 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 3.523 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.523 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 3.629681386s client | 3.523 DEBUG Setting timeout of 3.629681386s sim | Dropping packet 7 (1252 bytes) from 193.167.0.100 client | 7.154 DEBUG [recovery::Loss] PTO timer fired for Initial client | 7.154 DEBUG [Client 1e2577d4893abe907f7706fe8c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 332 }) client | 7.154 INFO Lost crypto frame space=in offset=1208 length=332 client | 7.154 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 7.154 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.154 DEBUG CRYPTO for in offset=1208, len=332 client | 7.154 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=22 type=Initial pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 376 client | TX -> Crypto { offset: 1208, len: 332 } client | 7.154 DEBUG [Client 1e2577d4893abe907f7706fe8c] pad Initial from 376 to PLPMTU 1252 client | 7.154 DEBUG packet_sent this=0x55f8641c1ad0, pn=22, ps=1252 client | 7.154 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 7.154 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.154 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 7.154 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.154 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.154 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.154 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 7.25950722s client | 7.154 DEBUG Setting timeout of 7.25950722s client | 7.188 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=1 type=Initial pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1200 client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Padding { len: 1064 } client | 7.188 DEBUG Write secret available for Handshake: SymKey [32]: 284d0d23713845d63174aa82449177e537c5af27a1fefa0098422556bda7e7d1 client | 7.188 DEBUG Read secret available for Handshake: SymKey [32]: c0f18dcf9211da4fbae0eb0adcba2b81528d732932074d0a6095890a68721927 client | 7.189 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.189 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 7.189 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 7.189 DEBUG [Crypto] Handshake keys installed client | 7.189 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 525209225 } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] State change from WaitVersion -> Handshaking client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=23 type=Initial pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 45 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 72, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] pad Initial from 45 to PLPMTU 1252 client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 7.224995576s client | 7.189 DEBUG Setting timeout of 7.224995576s client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=0 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 188 client | -> RX Crypto { offset: 0, len: 143 } client | 7.189 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.189 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 525950826 } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Initial dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=0 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 10, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] Drop packet number space in client | 7.189 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 113.332577ms client | 7.189 DEBUG Setting timeout of 113.332577ms client | 7.189 DEBUG StateChange(Handshaking) client | 7.189 WARN Cannot create stream ConnectionState client | 7.189 DEBUG stream BiDi creatable client | 7.189 WARN Cannot create stream ConnectionState client | 7.189 DEBUG stream UniDi creatable client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 113.294847ms client | 7.189 DEBUG Setting timeout of 113.294847ms client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=1 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Crypto { offset: 143, len: 1170 } client | 7.189 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.189 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 526363502 } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=1 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 6, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 113.005028ms client | 7.189 DEBUG Setting timeout of 113.005028ms client | 7.189 DEBUG stream BiDi creatable client | 7.189 WARN Cannot create stream ConnectionState client | 7.189 DEBUG stream UniDi creatable client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.189 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.189 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 112.976004ms client | 7.189 DEBUG Setting timeout of 112.976004ms client | 7.190 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=2 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Crypto { offset: 1313, len: 1170 } client | 7.190 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.190 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 527340831 } client | 7.190 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.190 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=2 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 7.190 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.190 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 112.049519ms client | 7.190 DEBUG Setting timeout of 112.049519ms client | 7.190 DEBUG stream BiDi creatable client | 7.190 WARN Cannot create stream ConnectionState client | 7.190 DEBUG stream UniDi creatable client | 7.190 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.190 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.190 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 112.017539ms client | 7.190 DEBUG Setting timeout of 112.017539ms client | 7.191 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=3 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Crypto { offset: 2483, len: 1170 } client | 7.191 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.191 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 528343044 } client | 7.191 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.191 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=3 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 4, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 7.191 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.191 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 111.023813ms client | 7.191 DEBUG Setting timeout of 111.023813ms client | 7.191 DEBUG stream BiDi creatable client | 7.191 WARN Cannot create stream ConnectionState client | 7.191 DEBUG stream UniDi creatable client | 7.191 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.191 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.191 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 110.994148ms client | 7.191 DEBUG Setting timeout of 110.994148ms client | 7.192 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=4 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Crypto { offset: 3653, len: 1170 } client | 7.192 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.192 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 529310664 } client | 7.192 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.192 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=4 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 5, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 7.192 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.192 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 110.047195ms client | 7.192 DEBUG Setting timeout of 110.047195ms client | 7.192 DEBUG stream BiDi creatable client | 7.192 WARN Cannot create stream ConnectionState client | 7.192 DEBUG stream UniDi creatable client | 7.192 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.192 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.192 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 110.022469ms client | 7.192 DEBUG Setting timeout of 110.022469ms client | 7.193 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=5 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Crypto { offset: 4823, len: 1170 } client | 7.193 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.193 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 530334277 } client | 7.193 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.193 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.193 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=5 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 4, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 7.193 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.193 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.193 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.193 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 109.00624ms client | 7.193 DEBUG Setting timeout of 109.00624ms client | 7.193 DEBUG stream BiDi creatable client | 7.193 WARN Cannot create stream ConnectionState client | 7.193 DEBUG stream UniDi creatable client | 7.193 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.193 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.193 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.193 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 108.975323ms client | 7.193 DEBUG Setting timeout of 108.975323ms client | 7.194 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=6 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Crypto { offset: 5993, len: 1170 } client | 7.194 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.194 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 531326393 } client | 7.194 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.194 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.194 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=6 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 3, first_ack_range: 6, ack_ranges: [], ecn_count: None } client | 7.194 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.194 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.194 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.194 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 108.060511ms client | 7.194 DEBUG Setting timeout of 108.060511ms client | 7.194 DEBUG stream BiDi creatable client | 7.194 WARN Cannot create stream ConnectionState client | 7.194 DEBUG stream UniDi creatable client | 7.194 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.194 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.194 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.194 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 108.036987ms client | 7.194 DEBUG Setting timeout of 108.036987ms client | 7.195 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=7 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Crypto { offset: 7163, len: 1170 } client | 7.195 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.195 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 532325010 } client | 7.195 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.195 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.195 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=7 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 4, first_ack_range: 7, ack_ranges: [], ecn_count: None } client | 7.195 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.195 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.195 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.195 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 107.060511ms client | 7.195 DEBUG Setting timeout of 107.060511ms client | 7.195 DEBUG stream BiDi creatable client | 7.195 WARN Cannot create stream ConnectionState client | 7.195 DEBUG stream UniDi creatable client | 7.195 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.195 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.195 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.195 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 107.027229ms client | 7.195 DEBUG Setting timeout of 107.027229ms client | 7.196 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=8 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Crypto { offset: 8333, len: 1170 } client | 7.196 DEBUG [Agent 0x55f8641ae500] state -> InProgress client | 7.196 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 533321744 } client | 7.196 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.196 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.196 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=8 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 3, first_ack_range: 8, ack_ranges: [], ecn_count: None } client | 7.196 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.196 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.196 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.196 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 106.066062ms client | 7.196 DEBUG Setting timeout of 106.066062ms client | 7.196 DEBUG stream BiDi creatable client | 7.196 WARN Cannot create stream ConnectionState client | 7.196 DEBUG stream UniDi creatable client | 7.196 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.196 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.196 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.196 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 106.038891ms client | 7.196 DEBUG Setting timeout of 106.038891ms client | 7.197 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=9 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 404 client | -> RX Crypto { offset: 9503, len: 358 } client | 7.197 DEBUG [Agent 0x55f8641ae500] state -> AuthenticationPending client | 7.197 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 533680422 } client | 7.197 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.197 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.197 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=9 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 50, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | 7.197 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.197 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.197 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.197 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 105.330532ms client | 7.197 DEBUG Setting timeout of 105.330532ms client | 7.197 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=10 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 125 client | -> RX Crypto { offset: 9861, len: 79 } client | 7.198 DEBUG [Agent 0x55f8641ae500] state -> AuthenticationPending client | 7.198 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 534157920 } client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=10 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 10, ack_delay: 70, first_ack_range: 10, ack_ranges: [], ecn_count: None } client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.198 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 104.69458ms client | 7.198 DEBUG Setting timeout of 104.69458ms client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=11 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 81 client | -> RX Crypto { offset: 9940, len: 36 } client | 7.198 DEBUG [Agent 0x55f8641ae500] state -> AuthenticationPending client | 7.198 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 534796979 } client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=11 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 11, ack_delay: 6, first_ack_range: 11, ack_ranges: [], ecn_count: None } client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.198 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 104.569897ms client | 7.198 DEBUG Setting timeout of 104.569897ms client | 7.198 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=12 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ping client | 7.198 DEBUG packet_sent this=0x55f8641c1ad0, pn=12, ps=41 client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.198 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 113.389571ms client | 7.198 DEBUG Setting timeout of 113.389571ms client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] Authenticated Ok client | 7.198 DEBUG Write secret available for ApplicationData: SymKey [32]: 71a2a6f4dc9f9b3a714d95a7cabfe1e1a5adaa55c1559db952acf3b1e8df5770 client | 7.198 DEBUG Read secret available for ApplicationData: SymKey [32]: d509d89df8e4f9aa034b253992983f95276865292265dbdf543c1bba004a7a74 client | 7.198 DEBUG [Agent 0x55f8641ae500] SSL_AuthCertificateComplete: Ok(()) client | 7.198 DEBUG [Agent 0x55f8641ae500] 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.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] TLS connection complete client | 7.198 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 7.198 DEBUG [Crypto] Application write key installed client | 7.198 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 7.198 DEBUG [Crypto] application read keys installed client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] State change from Handshaking -> Connected client | 7.198 INFO [Client 1e2577d4893abe907f7706fe8c] Connection established client | 7.198 DEBUG stream BiDi creatable client | 7.198 INFO Created stream 0 for https://server4/ghnydsfcpr client | 7.198 INFO Saving https://server4/ghnydsfcpr to "/downloads/ghnydsfcpr" client | 7.198 DEBUG stream UniDi creatable client | 7.198 DEBUG StateChange(Connected) client | 7.198 DEBUG stream 0 writable client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.198 DEBUG CRYPTO for hs offset=0, len=36 client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=13 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 77 client | TX -> Crypto { offset: 0, len: 36 } client | 7.198 DEBUG packet_sent this=0x55f8641c1ad0, pn=13, ps=77 client | 7.198 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=0 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 127 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 7.198 DEBUG packet_sent this=0x55f8641c1ad0, pn=0, ps=50 client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.198 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.198 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.198 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 113.35659ms client | 7.198 DEBUG Setting timeout of 113.35659ms server | xqc_hq_parse_req|247||hq recv CR LF| client | 7.229 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=12 type=Handshake pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 46 client | -> RX Ack { largest_acknowledged: 12, ack_delay: 11, first_ack_range: 12, ack_ranges: [], ecn_count: None } client | 7.229 DEBUG [Client 1e2577d4893abe907f7706fe8c] Rx ACK space=hs, ranges=[0..=12] client | 7.229 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=12 client | 7.229 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 127/12561 ssthresh 18446744073709551615] slow start += 41 client | 7.229 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 127/12561 ssthresh 18446744073709551615] on_packets_acked this=0x55f8641c1ad0, limited=0, bytes_in_flight=127, cwnd=12561, state=SlowStart, new_acked=41 client | 7.229 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.229 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.229 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.229 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.229 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 70.363973ms client | 7.229 DEBUG Setting timeout of 70.363973ms client | 7.229 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.229 DEBUG Building Handshake dcid Some(CID [12]: a10fea16ffe854423c5a605a) scid Some(CID [0]: ) client | 7.229 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.229 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.229 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 70.323989ms client | 7.229 DEBUG Setting timeout of 70.323989ms client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=0 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 22 client | -> RX HandshakeDone client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] State change from Connected -> Confirmed client | 7.230 DEBUG PMTUD started with probe size 1380 client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] Drop packet number space hs client | 7.230 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] No preferred address to migrate to client | 7.230 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 586781611 } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG Sending PMTUD probe of size 1380, count 1 client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=1 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ping client | TX -> Padding { len: 1321 } client | 7.230 DEBUG packet_sent this=0x55f8641c1ad0, pn=1, ps=1352 client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG ECN probing: sent 1 probes client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.852786ms client | 7.230 DEBUG Setting timeout of 19.852786ms client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=1 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 32 client | -> RX NewToken { token: [0, 193, 167, 0, 100, 104, 204, 120, 127] } client | 7.230 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 566954071 } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=2 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 2, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 7.230 DEBUG ECN probing: sent 2 probes client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 125.511797ms client | 7.230 DEBUG Setting timeout of 125.511797ms client | 7.230 WARN Unhandled event StateChange(Confirmed) client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 125.489035ms client | 7.230 DEBUG Setting timeout of 125.489035ms client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=9 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 282 client | -> RX Crypto { offset: 0, len: 257 } client | 7.230 DEBUG [0x55f8641ae500] Got resumption token [5978]: 0200063e8d28bec6..f0124a09bf2f4d55 client | 7.230 DEBUG [Agent 0x55f8641ae500] 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.230 DEBUG setting max_stream_data to 16777216 client | 7.230 DEBUG resumption token [6087]: 0000000124405c00..f0124a09bf2f4d55 client | 7.230 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 567155997 } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=3 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 37 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 18, first_ack_range: 0, ack_ranges: [AckRange { gap: 6, range: 1 }], ecn_count: None } client | 7.230 DEBUG ECN probing: sent 3 probes client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 125.197102ms client | 7.230 DEBUG Setting timeout of 125.197102ms client | 7.230 DEBUG stream BiDi creatable client | 7.230 DEBUG stream UniDi creatable client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 125.174279ms client | 7.230 DEBUG Setting timeout of 125.174279ms client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=10 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 283 client | -> RX Crypto { offset: 257, len: 257 } client | 7.230 DEBUG [0x55f8641ae500] Got resumption token [5978]: 0200063e8d28bec7..38a195797da2fee7 client | 7.230 DEBUG [Agent 0x55f8641ae500] 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.230 DEBUG setting max_stream_data to 16777216 client | 7.230 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 587480519 } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.900404ms client | 7.230 DEBUG Setting timeout of 19.900404ms client | 7.230 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 2 client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.230 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.230 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.871511ms client | 7.230 DEBUG Setting timeout of 19.871511ms client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=11 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 53 client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [89, 176, 115, 25, 4, 62, 64, 106, 234, 4, 108, 8], stateless_reset_token: [99, 161, 176, 186, 227, 90, 193, 252, 59, 40, 153, 97, 35, 253, 130, 156] } client | 7.231 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 567625800 } client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.231 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=4 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 37 client | TX -> Ack { largest_acknowledged: 11, ack_delay: 2, first_ack_range: 2, ack_ranges: [AckRange { gap: 6, range: 1 }], ecn_count: None } client | 7.231 DEBUG ECN probing: sent 4 probes client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.231 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.231 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 124.859014ms client | 7.231 DEBUG Setting timeout of 124.859014ms client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=12 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 53 client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [239, 233, 166, 76, 136, 253, 241, 150, 150, 79, 38, 140], stateless_reset_token: [237, 91, 255, 192, 13, 69, 70, 99, 77, 236, 182, 171, 236, 142, 169, 247] } client | 7.231 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 587718081 } client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.231 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.231 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.979762ms client | 7.231 DEBUG Setting timeout of 19.979762ms client | 7.231 DEBUG stream BiDi creatable client | 7.231 DEBUG stream UniDi creatable client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.231 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.231 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.960567ms client | 7.231 DEBUG Setting timeout of 19.960567ms client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=13 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 0, len: 1191, fin: false } client | 7.231 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 568587569 } client | 7.231 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.231 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.232 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=5 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 37 client | TX -> Ack { largest_acknowledged: 13, ack_delay: 3, first_ack_range: 4, ack_ranges: [AckRange { gap: 6, range: 1 }], ecn_count: None } client | 7.232 DEBUG ECN probing: sent 5 probes client | 7.232 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.232 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.232 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.232 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 123.884872ms client | 7.232 DEBUG Setting timeout of 123.884872ms client | 7.232 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.232 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.232 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.232 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 123.859856ms client | 7.232 DEBUG Setting timeout of 123.859856ms client | 7.232 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=14 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 1191, len: 1189, fin: false } client | 7.233 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 589603939 } client | 7.233 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.233 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.233 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.233 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.973561ms client | 7.233 DEBUG Setting timeout of 19.973561ms client | 7.233 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.233 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.233 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.233 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.953033ms client | 7.233 DEBUG Setting timeout of 19.953033ms client | 7.233 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=15 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 2380, len: 1189, fin: false } client | 7.234 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 570592498 } client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.234 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=6 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 37 client | TX -> Ack { largest_acknowledged: 15, ack_delay: 6, first_ack_range: 6, ack_ranges: [AckRange { gap: 6, range: 1 }], ecn_count: None } client | 7.234 DEBUG ECN probing: sent 6 probes client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.234 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.234 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 121.857361ms client | 7.234 DEBUG Setting timeout of 121.857361ms client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.234 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.234 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 121.826163ms client | 7.234 DEBUG Setting timeout of 121.826163ms client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=16 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 3569, len: 1189, fin: false } client | 7.234 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 591584192 } client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.234 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.234 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.977448ms client | 7.234 DEBUG Setting timeout of 19.977448ms client | 7.234 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.234 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.234 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 19.96234ms client | 7.235 DEBUG Setting timeout of 19.96234ms client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=17 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, NotEct) len 389 client | -> RX Stream { stream_id: 0, offset: 4758, len: 362, fin: true } client | 7.235 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 627, tv_nsec: 571920518 } client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.235 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=7 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 37 client | TX -> Ack { largest_acknowledged: 17, ack_delay: 3, first_ack_range: 8, ack_ranges: [AckRange { gap: 6, range: 1 }], ecn_count: None } client | 7.235 DEBUG ECN probing: sent 7 probes client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.235 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.235 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 120.557503ms client | 7.235 DEBUG Setting timeout of 120.557503ms client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.235 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.235 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] delay duration 120.467746ms client | 7.235 DEBUG Setting timeout of 120.467746ms client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 627, tv_nsec: 949220550 } } client | 7.235 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 627, tv_nsec: 949220550 } }) client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.235 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.235 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 627, tv_nsec: 572117174 } client | 7.235 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=8 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 48 client | TX -> Ack { largest_acknowledged: 17, ack_delay: 24, first_ack_range: 8, ack_ranges: [AckRange { gap: 6, range: 1 }], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.235 DEBUG ECN probing: sent 8 probes client | 7.235 DEBUG Setting timeout of 377.04614ms client | 7.255 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.255 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.255 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 627, tv_nsec: 592054918 } client | 7.255 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=9 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 49 client | TX -> Ack { largest_acknowledged: 17, ack_delay: 2516, first_ack_range: 8, ack_ranges: [AckRange { gap: 6, range: 1 }], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.255 DEBUG ECN probing: sent 9 probes client | 7.255 DEBUG Setting timeout of 357.096293ms client | 7.255 DEBUG Setting timeout of 357.08419ms client | 7.266 DEBUG [Client 1e2577d4893abe907f7706fe8c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.266 DEBUG Building Short dcid Some(CID [12]: a10fea16ffe854423c5a605a) client | 7.266 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 627, tv_nsec: 602693667 } client | 7.266 DEBUG [Client 1e2577d4893abe907f7706fe8c] pn=10 type=Short pri-path:a10fea16ffe854423c5a605a 0.0.0.0:60765->193.167.100.100:443 Tos(Cs0, Ect0) len 49 client | TX -> Ack { largest_acknowledged: 17, ack_delay: 3846, first_ack_range: 8, ack_ranges: [AckRange { gap: 6, range: 1 }], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.266 DEBUG ECN probing: sent 10 probes client | 7.266 DEBUG ECN probing concluded with 10 probes sent client | 7.266 DEBUG Setting timeout of 346.454689ms client | 7.266 DEBUG Setting timeout of 346.442016ms server | send_count:24, lost_count:0, tlp_count:0, recv_count:27, srtt:34412 early_data_flag:0, conn_err:0, ack_info:#10-0#0-0#0-0#v0429, path_info:0/1/0/a/0,1,1,1,0,0,0,0,0,0,0,30,31,27,5120,0,17,i32,4,36,49,0,10,7257,0,4,35,48,40004,8,7227,0,4,36,49,0,9,7247,0,4,373,80,17,7192,4,11,4,18,7216,4,9,40000,19,7227,0-2,0-2,0-0,0-0,0-0,0-34,0-17947,0-6159,0-24,0-27,0-0,0-0,0-0,0-0,0-0,0-0,0-0,0-0 client | 7.613 DEBUG [Client 1e2577d4893abe907f7706fe8c] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 627, tv_nsec: 949220550 } } -> Closed(Application(0)) client | 7.613 INFO Closing timer expired client | 7.613 DEBUG Output::None client | 7.613 WARN Unhandled event StateChange(Closed(Application(0))) client | 7.613 DEBUG Timer fired while closed client | 7.613 DEBUG Output::None client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-09-11 21:24:26,049 Using the client's key log file. 2025-09-11 21:24:26,062 2025-09-11 21:24:26,063 Using the client's key log file. 2025-09-11 21:24:26,063 Using the client's key log file. 2025-09-11 21:24:26,075 2025-09-11 21:24:26,076 Using the client's key log file. 2025-09-11 21:24:26,076 Using the client's key log file. 2025-09-11 21:24:26,628 Check of downloaded files succeeded. 2025-09-11 21:24:26,922 Server sent 9976 bytes in Handshake CRYPTO frames. 2025-09-11 21:24:27,275 Received a 1252 byte Initial packet from the client. Amplification limit: 3756 2025-09-11 21:24:27,275 Received a 48 byte Handshake packet from the server. Total: 48 2025-09-11 21:24:27,275 Received a 1252 byte Initial packet from the client. Amplification limit: 7512 2025-09-11 21:24:27,275 Received a 1200 byte Handshake packet from the server. Total: 1248 2025-09-11 21:24:27,275 Received a 188 byte Handshake packet from the server. Total: 1436 2025-09-11 21:24:27,276 Received a 1216 byte Handshake packet from the server. Total: 2652 2025-09-11 21:24:27,276 Received a 1216 byte Handshake packet from the server. Total: 3868 2025-09-11 21:24:27,276 Received a 1216 byte Handshake packet from the server. Total: 5084 2025-09-11 21:24:27,276 Received a 1216 byte Handshake packet from the server. Total: 6300 2025-09-11 21:24:27,276 Received a 1216 byte Handshake packet from the server. Total: 7516 2025-09-11 21:24:27,276 Server violated the amplification limit, but stayed within 3-4x amplification. Letting it slide. 2025-09-11 21:24:27,276 Received a 1216 byte Handshake packet from the server. Total: 8732 2025-09-11 21:24:27,276 Server violated the amplification limit, but stayed within 3-4x amplification. Letting it slide. 2025-09-11 21:24:27,276 Received a 1216 byte Handshake packet from the server. Total: 9948 2025-09-11 21:24:27,276 Server violated the amplification limit, but stayed within 3-4x amplification. Letting it slide. 2025-09-11 21:24:27,276 Received a 1216 byte Handshake packet from the server. Total: 11164 2025-09-11 21:24:27,276 Server violated the amplification limit.