2025-04-30 08:45:18,074 Generated random file: ebhccgnfup of size: 5120 2025-04-30 08:45:18,074 Requests: https://server4:443/ebhccgnfup 2025-04-30 08:45:18,461 2025-04-30 08:45:18,462 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_dxblozvb/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_33h_6qfe/ DOWNLOADS=/tmp/download_ljp4ujgv/ SERVER_LOGS=/tmp/logs_server_s4t4nbmw CLIENT_LOGS=/tmp/logs_client__9t5wmc1 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/facebook/proxygen/mvfst-interop:latest REQUESTS="https://server4:443/ebhccgnfup" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 08:45:32,895 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 | + set -o nounset server | + VERSION=1 server | + HQ_CLI=/proxygen/_build/proxygen/bin/hq server | + PORT=443 server | + LOGLEVEL=2 server | + /setup.sh 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 | + PROTOCOL=hq-interop server | + HTTPVERSION=0.9 server | + CONN_FLOW_CONTROL=107374182 server | + STREAM_FLOW_CONTROL=107374182 server | ./run_endpoint.sh: line 28: REQUESTS: unbound variable server | ++ tr ' ' '\n' server | ++ awk -F / '{ print "/" $4 }' server | ++ paste -sd, server | + INVOCATIONS= server | + EARLYDATA=false server | + PSK_FILE= server | + '[' -n transfer ']' server | + case "${TESTCASE}" in server | + STREAM_FLOW_CONTROL=262144 server | + CONN_FLOW_CONTROL=2621440 server | + '[' server == client ']' server | + '[' server == server ']' server | + echo 'Running QUIC server on [::]:443' server | Running QUIC server on [::]:443 server | + /proxygen/_build/proxygen/bin/hq --mode=server --cert=/certs/cert.pem --key=/certs/priv.key --conn_flow_control=2621440 --stream_flow_control=262144 --port=443 --httpversion=0.9 --h2port=443 --static_root=/www --logdir=/logs --qlogger_path=/logs --host=:: --congestion=bbr --pacing=true --v=2 server | + tee /logs/server.log 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 | W20250430 08:45:19.036243 25 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:45:19.036314 25 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:45:19.036321 25 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:45:19.036326 25 QuicServerWorker.cpp:1234] HostId is already set to 0 server | I20250430 08:45:19.036756 25 HQServer.cpp:283] HQ server started at: [::]:443 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.056688586s 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/ebhccgnfup ']' 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/ebhccgnfup client | ' client | ++ tee -i -a /logs/client.log client | 0.000 DEBUG Logging initialized client | 0.001 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:53795 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=e200c36dc13c6b41 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:53795->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443] Path validated Instant { tv_sec: 384, tv_nsec: 389180273 } client | 0.001 DEBUG [Client e200c36dc13c6b41] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 0.002 DEBUG [Client e200c36dc13c6b41] State change from Init -> WaitInitial client | 0.002 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1213 client | 0.002 DEBUG [Client e200c36dc13c6b41] pn=0 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.002 DEBUG packet_sent this=0x55663ba0a970, pn=0, ps=1252 client | 0.002 DEBUG ECN probing: sent 1 probes client | 0.002 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=326 client | 0.002 DEBUG [Client e200c36dc13c6b41] pn=1 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.002 DEBUG [Client e200c36dc13c6b41] pad Initial from 366 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55663ba0a970, pn=1, ps=1252 client | 0.002 DEBUG ECN probing: sent 2 probes client | 0.002 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) 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 e200c36dc13c6b41] delay duration 3.902299ms client | 0.002 DEBUG Setting timeout of 3.902299ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) 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 e200c36dc13c6b41] delay duration 3.877101ms client | 0.002 DEBUG Setting timeout of 3.877101ms client | 0.007 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) 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 e200c36dc13c6b41] delay duration 294.821024ms client | 0.007 DEBUG Setting timeout of 294.821024ms sim | Dropping packet 2 (1252 bytes) from 193.167.0.100 client | 0.303 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.303 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.303 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.303 DEBUG [Client e200c36dc13c6b41] Lost: EcnEct0 client | 0.303 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 0.303 INFO Lost crypto frame space=in offset=1213 length=326 client | 0.303 DEBUG [Client e200c36dc13c6b41] Lost: EcnEct0 client | 0.303 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.303 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 0.303 DEBUG CRYPTO for in offset=0, len=1213 client | 0.303 DEBUG [Client e200c36dc13c6b41] pn=2 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.303 DEBUG packet_sent this=0x55663ba0a970, pn=2, ps=1252 client | 0.303 DEBUG ECN probing: sent 3 probes client | 0.304 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.304 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 0.304 DEBUG CRYPTO for in offset=1213, len=326 client | 0.304 DEBUG [Client e200c36dc13c6b41] pn=3 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.304 DEBUG [Client e200c36dc13c6b41] pad Initial from 366 to PLPMTU 1252 client | 0.304 DEBUG packet_sent this=0x55663ba0a970, pn=3, ps=1252 client | 0.304 DEBUG ECN probing: sent 4 probes client | 0.304 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.304 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 0.304 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.304 DEBUG [Client e200c36dc13c6b41] delay duration 599.897608ms client | 0.304 DEBUG Setting timeout of 599.897608ms sim | Dropping packet 3 (1252 bytes) from 193.167.0.100 sim | Dropping packet 4 (1252 bytes) from 193.167.0.100 client | 0.904 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.904 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.904 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.904 DEBUG [Client e200c36dc13c6b41] Lost: EcnEct0 client | 0.904 DEBUG ECN validation failed, all 3 initial marked packets were lost client | 0.904 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 0.904 INFO Lost crypto frame space=in offset=1213 length=326 client | 0.904 DEBUG [Client e200c36dc13c6b41] Lost: EcnEct0 client | 0.904 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.904 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 0.904 DEBUG CRYPTO for in offset=0, len=1213 client | 0.904 DEBUG [Client e200c36dc13c6b41] pn=4 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.905 DEBUG packet_sent this=0x55663ba0a970, pn=4, ps=1252 client | 0.905 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.905 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 0.905 DEBUG CRYPTO for in offset=1213, len=326 client | 0.905 DEBUG [Client e200c36dc13c6b41] pn=5 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.905 DEBUG [Client e200c36dc13c6b41] pad Initial from 366 to PLPMTU 1252 client | 0.905 DEBUG packet_sent this=0x55663ba0a970, pn=5, ps=1252 client | 0.905 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.905 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 0.905 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.905 DEBUG [Client e200c36dc13c6b41] delay duration 1.199926392s client | 0.905 DEBUG Setting timeout of 1.199926392s sim | Dropping packet 5 (1252 bytes) from 193.167.0.100 sim | Dropping packet 6 (1252 bytes) from 193.167.0.100 client | 2.106 DEBUG [LossRecovery] PTO timer fired for Initial client | 2.106 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 2.106 INFO Lost crypto frame space=in offset=0 length=1213 client | 2.106 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 2.106 INFO Lost crypto frame space=in offset=1213 length=326 client | 2.106 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 2.106 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 2.106 DEBUG CRYPTO for in offset=0, len=1213 client | 2.106 DEBUG [Client e200c36dc13c6b41] pn=6 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 2.106 DEBUG packet_sent this=0x55663ba0a970, pn=6, ps=1252 client | 2.106 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.106 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 2.106 DEBUG CRYPTO for in offset=1213, len=326 client | 2.106 DEBUG [Client e200c36dc13c6b41] pn=7 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 2.106 DEBUG [Client e200c36dc13c6b41] pad Initial from 366 to PLPMTU 1252 client | 2.106 DEBUG packet_sent this=0x55663ba0a970, pn=7, ps=1252 client | 2.106 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.106 DEBUG Building Initial dcid Some(CID [8]: e200c36dc13c6b41) scid Some(CID [0]: ) client | 2.106 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.106 DEBUG [Client e200c36dc13c6b41] delay duration 2.39993591s client | 2.106 DEBUG Setting timeout of 2.39993591s sim | Dropping packet 7 (1252 bytes) from 193.167.0.100 client | 2.140 DEBUG [Client e200c36dc13c6b41] pn=12108050 type=Initial pri-path:e200c36dc13c6b41 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Ack { largest_acknowledged: 7, ack_delay: 83, first_ack_range: 0, ack_ranges: [AckRange { gap: 5, range: 0 }], ecn_count: None } client | -> RX Padding { len: 1092 } client | 2.140 DEBUG Read Ok(90) bytes client | 2.141 DEBUG Write secret available for Handshake: SymKey [32]: ad71e039b205c0d411d80ebe72f29712f181e06852086dba0d259fb5afa598a0 client | 2.141 DEBUG Read secret available for Handshake: SymKey [32]: 75dbd629cd509ffbde8dbacb305507e3e118f7949e497a8983ab9d9a8cf78b32 client | 2.141 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.141 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 2.141 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 2.141 DEBUG [Crypto] Handshake keys installed client | 2.141 DEBUG [Client e200c36dc13c6b41] Rx ACK space=in, ranges=[7..=7, 0..=0] client | 2.141 DEBUG [LossRecovery] ACK for Initial - largest_acked=7 client | 2.141 DEBUG packet_lost this=0x55663ba0a970, pn=1, ps=1252 client | 2.141 DEBUG packet_lost this=0x55663ba0a970, pn=2, ps=1252 client | 2.141 DEBUG packet_lost this=0x55663ba0a970, pn=3, ps=1252 client | 2.141 DEBUG packet_lost this=0x55663ba0a970, pn=4, ps=1252 client | 2.141 DEBUG packet_lost this=0x55663ba0a970, pn=5, ps=1252 client | 2.141 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3756/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764 client | 2.141 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3756/8764 ssthresh 8764] state -> RecoveryStart client | 2.141 DEBUG on_packets_lost this=0x55663ba0a970, bytes_in_flight=3756, cwnd=8764, state=RecoveryStart client | 2.141 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 386, tv_nsec: 528833756 })] CongCtrl 1252/8764 ssthresh 8764] on_packets_acked this=0x55663ba0a970, limited=0, bytes_in_flight=1252, cwnd=8764, state=RecoveryStart, new_acked=0 client | 2.141 DEBUG Acked crypto frame space=in offset=1213 length=326 client | 2.141 DEBUG Acked crypto frame space=in offset=0 length=1213 client | 2.141 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 2.141 INFO Lost crypto frame space=in offset=1213 length=326 client | 2.141 DEBUG [Client e200c36dc13c6b41] Lost: EcnEct0 client | 2.141 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 2.141 INFO Lost crypto frame space=in offset=0 length=1213 client | 2.141 DEBUG [Client e200c36dc13c6b41] Lost: EcnEct0 client | 2.141 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 2.141 INFO Lost crypto frame space=in offset=1213 length=326 client | 2.141 DEBUG [Client e200c36dc13c6b41] Lost: EcnEct0 client | 2.141 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 2.141 INFO Lost crypto frame space=in offset=0 length=1213 client | 2.141 DEBUG [Client e200c36dc13c6b41] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 2.141 INFO Lost crypto frame space=in offset=1213 length=326 client | 2.141 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 528833756 } client | 2.141 DEBUG [Client e200c36dc13c6b41] Changing to use Server CID=4000001b184aae7e client | 2.141 DEBUG [Client e200c36dc13c6b41] State change from WaitInitial -> Handshaking client | 2.141 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG Building Initial dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG [Client e200c36dc13c6b41] pn=8 type=Initial pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 12108050, ack_delay: 91, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 2.141 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG [Client e200c36dc13c6b41] pad Initial from 44 to PLPMTU 1252 client | 2.141 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG Building Initial dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG [Client e200c36dc13c6b41] delay duration 3.242911ms client | 2.141 DEBUG Setting timeout of 3.242911ms client | 2.141 DEBUG StateChange(Handshaking) client | 2.141 WARN Cannot create stream ConnectionState client | 2.141 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG Building Initial dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG [Client e200c36dc13c6b41] delay duration 3.211833ms client | 2.141 DEBUG Setting timeout of 3.211833ms client | 2.141 DEBUG [Client e200c36dc13c6b41] pn=12108050 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 0, len: 1191 } client | 2.141 DEBUG Read Ok(1191) bytes client | 2.141 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.141 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 529801255 } client | 2.141 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG Building Initial dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG [Client e200c36dc13c6b41] pn=0 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 12108050, ack_delay: 7, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 2.141 DEBUG [Client e200c36dc13c6b41] Drop packet number space in client | 2.141 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 2.141 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG [Client e200c36dc13c6b41] delay duration 102.290585ms client | 2.141 DEBUG Setting timeout of 102.290585ms client | 2.141 DEBUG stream BiDi creatable client | 2.141 WARN Cannot create stream ConnectionState client | 2.141 DEBUG stream UniDi creatable client | 2.141 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.141 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.141 DEBUG [Client e200c36dc13c6b41] delay duration 102.265909ms client | 2.141 DEBUG Setting timeout of 102.265909ms client | 2.142 DEBUG [Client e200c36dc13c6b41] pn=12108051 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 1191, len: 1190 } client | 2.142 DEBUG Read Ok(1190) bytes client | 2.142 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.142 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 530822836 } client | 2.142 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.142 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.142 DEBUG [Client e200c36dc13c6b41] pn=1 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 12108051, ack_delay: 5, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 2.142 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.142 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.142 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.142 DEBUG [Client e200c36dc13c6b41] delay duration 101.30941ms client | 2.142 DEBUG Setting timeout of 101.30941ms client | 2.142 DEBUG stream BiDi creatable client | 2.142 WARN Cannot create stream ConnectionState client | 2.142 DEBUG stream UniDi creatable client | 2.142 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.142 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.142 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.142 DEBUG [Client e200c36dc13c6b41] delay duration 101.279985ms client | 2.142 DEBUG Setting timeout of 101.279985ms client | 2.143 DEBUG [Client e200c36dc13c6b41] pn=12108052 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 2381, len: 1190 } client | 2.143 DEBUG Read Ok(1190) bytes client | 2.143 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.143 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 531835810 } client | 2.143 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.143 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.143 DEBUG [Client e200c36dc13c6b41] pn=2 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 12108052, ack_delay: 4, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 2.143 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.143 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.143 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.143 DEBUG [Client e200c36dc13c6b41] delay duration 100.285912ms client | 2.143 DEBUG Setting timeout of 100.285912ms client | 2.143 DEBUG stream BiDi creatable client | 2.143 WARN Cannot create stream ConnectionState client | 2.143 DEBUG stream UniDi creatable client | 2.143 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.143 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.143 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.143 DEBUG [Client e200c36dc13c6b41] delay duration 100.256016ms client | 2.143 DEBUG Setting timeout of 100.256016ms client | 2.144 DEBUG [Client e200c36dc13c6b41] pn=12108053 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 3571, len: 1190 } client | 2.144 DEBUG Read Ok(1190) bytes client | 2.144 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.144 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 532864114 } client | 2.144 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.144 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.144 DEBUG [Client e200c36dc13c6b41] pn=3 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 12108053, ack_delay: 4, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 2.144 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.144 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.144 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.144 DEBUG [Client e200c36dc13c6b41] delay duration 99.284934ms client | 2.144 DEBUG Setting timeout of 99.284934ms client | 2.144 DEBUG stream BiDi creatable client | 2.144 WARN Cannot create stream ConnectionState client | 2.144 DEBUG stream UniDi creatable client | 2.144 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.144 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.144 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.144 DEBUG [Client e200c36dc13c6b41] delay duration 99.25643ms client | 2.144 DEBUG Setting timeout of 99.25643ms client | 2.145 DEBUG [Client e200c36dc13c6b41] pn=12108054 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 4761, len: 1190 } client | 2.145 DEBUG Read Ok(1190) bytes client | 2.145 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.145 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 533830902 } client | 2.145 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.145 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.145 DEBUG [Client e200c36dc13c6b41] pn=4 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 12108054, ack_delay: 5, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 2.145 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.145 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.145 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.145 DEBUG [Client e200c36dc13c6b41] delay duration 98.301184ms client | 2.145 DEBUG Setting timeout of 98.301184ms client | 2.145 DEBUG stream BiDi creatable client | 2.145 WARN Cannot create stream ConnectionState client | 2.145 DEBUG stream UniDi creatable client | 2.145 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.145 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.145 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.145 DEBUG [Client e200c36dc13c6b41] delay duration 98.271568ms client | 2.145 DEBUG Setting timeout of 98.271568ms client | 2.146 DEBUG [Client e200c36dc13c6b41] pn=12108055 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 5951, len: 1190 } client | 2.146 DEBUG Read Ok(1190) bytes client | 2.146 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.146 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 534869719 } client | 2.146 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.146 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.146 DEBUG [Client e200c36dc13c6b41] pn=5 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 12108055, ack_delay: 4, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 2.146 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.146 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.147 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.147 DEBUG [Client e200c36dc13c6b41] delay duration 97.246267ms client | 2.147 DEBUG Setting timeout of 97.246267ms client | 2.147 DEBUG stream BiDi creatable client | 2.147 WARN Cannot create stream ConnectionState client | 2.147 DEBUG stream UniDi creatable client | 2.147 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.147 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.147 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.147 DEBUG [Client e200c36dc13c6b41] delay duration 97.198748ms client | 2.147 DEBUG Setting timeout of 97.198748ms client | 2.147 DEBUG [Client e200c36dc13c6b41] pn=12108056 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 7141, len: 1190 } client | 2.147 DEBUG Read Ok(1190) bytes client | 2.147 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.147 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 535881377 } client | 2.147 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.147 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.147 DEBUG [Client e200c36dc13c6b41] pn=6 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 12108056, ack_delay: 4, first_ack_range: 6, ack_ranges: [], ecn_count: None } client | 2.147 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.147 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.147 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.147 DEBUG [Client e200c36dc13c6b41] delay duration 96.264114ms client | 2.147 DEBUG Setting timeout of 96.264114ms client | 2.147 DEBUG stream BiDi creatable client | 2.147 WARN Cannot create stream ConnectionState client | 2.147 DEBUG stream UniDi creatable client | 2.147 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.148 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.148 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.148 DEBUG [Client e200c36dc13c6b41] delay duration 96.236452ms client | 2.148 DEBUG Setting timeout of 96.236452ms client | 2.148 DEBUG [Client e200c36dc13c6b41] pn=12108057 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Crypto { offset: 8331, len: 1190 } client | 2.148 DEBUG Read Ok(1190) bytes client | 2.148 DEBUG [Agent 0x55663b9f7270] state -> InProgress client | 2.148 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 536875086 } client | 2.148 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.148 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.148 DEBUG [Client e200c36dc13c6b41] pn=7 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 12108057, ack_delay: 4, first_ack_range: 7, ack_ranges: [], ecn_count: None } client | 2.148 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.148 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.148 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.148 DEBUG [Client e200c36dc13c6b41] delay duration 95.266528ms client | 2.148 DEBUG Setting timeout of 95.266528ms client | 2.148 DEBUG stream BiDi creatable client | 2.148 WARN Cannot create stream ConnectionState client | 2.148 DEBUG stream UniDi creatable client | 2.148 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.148 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.149 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.149 DEBUG [Client e200c36dc13c6b41] delay duration 95.238355ms client | 2.149 DEBUG Setting timeout of 95.238355ms client | 2.149 DEBUG [Client e200c36dc13c6b41] pn=12108058 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 477 client | -> RX Crypto { offset: 9521, len: 435 } client | 2.149 DEBUG Read Ok(435) bytes client | 2.149 DEBUG [Agent 0x55663b9f7270] state -> AuthenticationPending client | 2.149 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 537329840 } client | 2.149 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.149 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.149 DEBUG [Client e200c36dc13c6b41] pn=8 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 12108058, ack_delay: 81, first_ack_range: 8, ack_ranges: [], ecn_count: None } client | 2.150 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.150 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.150 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.150 DEBUG [Client e200c36dc13c6b41] delay duration 94.176936ms client | 2.150 DEBUG Setting timeout of 94.176936ms client | 2.150 DEBUG [Client e200c36dc13c6b41] Authenticated Ok client | 2.150 DEBUG Write secret available for ApplicationData: SymKey [32]: 46fa2222dd63a572da276b53b099ecb944f0f3c03b664682e21586fc4c43f4c2 client | 2.150 DEBUG Read secret available for ApplicationData: SymKey [32]: 8820017808afc6a6768f311753e15daa9562c1d7de3ea5543e56cdaf4a1c9800 client | 2.150 DEBUG [Agent 0x55663b9f7270] SSL_AuthCertificateComplete: Ok(()) client | 2.150 DEBUG [Agent 0x55663b9f7270] 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 | 2.150 DEBUG [Client e200c36dc13c6b41] TLS connection complete client | 2.150 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 2.150 DEBUG [Crypto] Application write key installed client | 2.150 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 2.150 DEBUG [Crypto] application read keys installed client | 2.150 DEBUG [Client e200c36dc13c6b41] State change from Handshaking -> Connected client | 2.150 INFO [Client e200c36dc13c6b41] Connection established client | 2.150 DEBUG stream BiDi creatable client | 2.150 INFO Created stream 0 for https://server4/ebhccgnfup client | 2.150 INFO Saving https://server4/ebhccgnfup to "/downloads/ebhccgnfup" client | 2.150 DEBUG stream UniDi creatable client | 2.150 DEBUG StateChange(Connected) client | 2.150 DEBUG stream 0 writable client | 2.150 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.150 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.150 DEBUG CRYPTO for hs offset=0, len=36 client | 2.150 DEBUG [Client e200c36dc13c6b41] pn=9 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 2.150 DEBUG packet_sent this=0x55663ba0a970, pn=9, ps=73 client | 2.150 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.150 DEBUG [Client e200c36dc13c6b41] pn=0 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 119 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 2.150 DEBUG packet_sent this=0x55663ba0a970, pn=0, ps=46 client | 2.150 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.150 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.150 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.150 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.150 DEBUG [Client e200c36dc13c6b41] delay duration 102.267382ms client | 2.150 DEBUG Setting timeout of 102.267382ms client | 2.181 DEBUG [Client e200c36dc13c6b41] pn=12108059 type=Handshake pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 38 client | -> RX Ack { largest_acknowledged: 9, ack_delay: 47, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | 2.181 DEBUG [Client e200c36dc13c6b41] Rx ACK space=hs, ranges=[0..=9] client | 2.181 DEBUG [LossRecovery] ACK for Handshake - largest_acked=9 client | 2.181 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 386, tv_nsec: 528833756 })] CongCtrl 46/8764 ssthresh 8764] state -> CongestionAvoidance client | 2.181 DEBUG on_packets_acked this=0x55663ba0a970, limited=1, bytes_in_flight=46, cwnd=8764, state=CongestionAvoidance, new_acked=73 client | 2.181 DEBUG Acked crypto frame space=hs offset=0 length=36 client | 2.181 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.181 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.181 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.181 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.181 DEBUG [Client e200c36dc13c6b41] delay duration 57.08671ms client | 2.181 DEBUG Setting timeout of 57.08671ms client | 2.181 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.181 DEBUG Building Handshake dcid Some(CID [8]: 4000001b184aae7e) scid Some(CID [0]: ) client | 2.181 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.181 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.181 DEBUG [Client e200c36dc13c6b41] delay duration 57.063978ms client | 2.181 DEBUG Setting timeout of 57.063978ms client | 2.181 DEBUG [Client e200c36dc13c6b41] pn=12108050 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 400 client | -> RX Crypto { offset: 0, len: 250 } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 52, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [64, 0, 0, 24, 30, 172, 54, 252], stateless_reset_token: [210, 237, 85, 156, 190, 138, 135, 196, 218, 64, 194, 123, 228, 113, 239, 146] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [64, 0, 0, 7, 99, 167, 235, 174], stateless_reset_token: [92, 99, 6, 121, 217, 90, 238, 80, 77, 138, 105, 153, 136, 10, 104, 6] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [64, 0, 0, 3, 188, 11, 189, 155], stateless_reset_token: [8, 247, 128, 137, 251, 13, 14, 5, 188, 57, 251, 206, 5, 146, 72, 4] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [64, 0, 0, 24, 88, 73, 99, 13], stateless_reset_token: [106, 4, 0, 220, 8, 171, 85, 230, 208, 72, 213, 169, 208, 168, 176, 222] } client | -> RX Padding { len: 7 } client | 2.181 DEBUG Read Ok(250) bytes client | 2.181 DEBUG [0x55663b9f7270] Got resumption token [5979]: 02000633faf00948..939d3919a48deefc client | 2.181 DEBUG [Agent 0x55663b9f7270] 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 | 2.181 DEBUG setting max_stream_data to 262144 client | 2.181 DEBUG [Client e200c36dc13c6b41] Rx ACK space=ap, ranges=[0..=0] client | 2.181 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 client | 2.181 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 386, tv_nsec: 569795261 })] CongCtrl 0/8764 ssthresh 8764] on_packets_acked this=0x55663ba0a970, limited=0, bytes_in_flight=0, cwnd=8764, state=CongestionAvoidance, new_acked=0 client | 2.181 DEBUG [Client e200c36dc13c6b41] State change from Connected -> Confirmed client | 2.181 DEBUG PMTUD started with probe size 1380 client | 2.181 DEBUG [Client e200c36dc13c6b41] Drop packet number space hs client | 2.181 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 2.182 DEBUG [Client e200c36dc13c6b41] No preferred address to migrate to client | 2.182 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 569795261 } client | 2.182 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.182 DEBUG Sending PMTUD probe of size 1380, count 1 client | 2.182 DEBUG [Client e200c36dc13c6b41] pn=1 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1352 client | TX -> Ack { largest_acknowledged: 12108050, ack_delay: 28, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> Ping client | TX -> Padding { len: 1317 } client | 2.182 DEBUG packet_sent this=0x55663ba0a970, pn=1, ps=1352 client | 2.182 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.182 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG [Client e200c36dc13c6b41] delay duration 101.537075ms client | 2.182 DEBUG Setting timeout of 101.537075ms client | 2.182 DEBUG stream BiDi creatable client | 2.182 DEBUG stream UniDi creatable client | 2.182 DEBUG stream 0 complete client | 2.182 WARN Unhandled event StateChange(Confirmed) client | 2.182 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.182 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG [Client e200c36dc13c6b41] delay duration 101.510996ms client | 2.182 DEBUG Setting timeout of 101.510996ms client | 2.182 DEBUG [Client e200c36dc13c6b41] pn=12108051 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 0, len: >>1209, fin: false } client | 2.182 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 590779102 } client | 2.182 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.182 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG [Client e200c36dc13c6b41] delay duration 19.973922ms client | 2.182 DEBUG Setting timeout of 19.973922ms client | 2.182 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.182 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.182 DEBUG [Client e200c36dc13c6b41] delay duration 19.943835ms client | 2.182 DEBUG Setting timeout of 19.943835ms client | 2.183 DEBUG [Client e200c36dc13c6b41] pn=12108052 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 1209, len: >>1207, fin: false } client | 2.183 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 571788760 } client | 2.183 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.183 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.183 DEBUG [Client e200c36dc13c6b41] pn=2 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 12108052, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 2.183 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.183 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.183 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.183 DEBUG [Client e200c36dc13c6b41] delay duration 99.769409ms client | 2.183 DEBUG Setting timeout of 99.769409ms client | 2.183 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.183 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.183 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.183 DEBUG [Client e200c36dc13c6b41] delay duration 99.742999ms client | 2.183 DEBUG Setting timeout of 99.742999ms client | 2.184 DEBUG [Client e200c36dc13c6b41] pn=12108053 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 2416, len: >>1207, fin: false } client | 2.184 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 592799817 } client | 2.184 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.184 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.184 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.184 DEBUG [Client e200c36dc13c6b41] delay duration 19.977799ms client | 2.184 DEBUG Setting timeout of 19.977799ms client | 2.184 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.184 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.184 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.184 DEBUG [Client e200c36dc13c6b41] delay duration 19.963422ms client | 2.184 DEBUG Setting timeout of 19.963422ms client | 2.185 DEBUG [Client e200c36dc13c6b41] pn=12108054 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Stream { stream_id: 0, offset: 3623, len: >>1207, fin: false } client | 2.185 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 573804626 } client | 2.185 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.185 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.185 DEBUG [Client e200c36dc13c6b41] pn=3 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 12108054, ack_delay: 2, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 2.185 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.185 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.185 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.185 DEBUG [Client e200c36dc13c6b41] delay duration 97.764984ms client | 2.185 DEBUG Setting timeout of 97.764984ms client | 2.185 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.185 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.185 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.185 DEBUG [Client e200c36dc13c6b41] delay duration 97.738024ms client | 2.185 DEBUG Setting timeout of 97.738024ms client | 2.186 DEBUG [Client e200c36dc13c6b41] pn=12108055 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 336 client | -> RX Stream { stream_id: 0, offset: 4830, len: 290, fin: true } client | -> RX Padding { len: 19 } client | 2.186 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 594117505 } client | 2.186 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.186 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.186 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.186 DEBUG [Client e200c36dc13c6b41] delay duration 19.971116ms client | 2.186 DEBUG Setting timeout of 19.971116ms client | 2.186 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.186 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.186 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.186 DEBUG [Client e200c36dc13c6b41] delay duration 19.927304ms client | 2.186 DEBUG Setting timeout of 19.927304ms client | 2.186 DEBUG [Client e200c36dc13c6b41] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 386, tv_nsec: 879091778 } } client | 2.186 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 386, tv_nsec: 879091778 } }) client | 2.186 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.186 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.186 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 386, tv_nsec: 574251786 } client | 2.186 DEBUG [Client e200c36dc13c6b41] pn=4 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 45 client | TX -> Ack { largest_acknowledged: 12108055, ack_delay: 16, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 2.186 DEBUG Setting timeout of 304.780741ms server | I20250430 08:45:22.264405 32 H1QDownstreamSession.h:84] onConnectionEnd client | 2.216 DEBUG [Client e200c36dc13c6b41] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.216 DEBUG Building Short dcid Some(CID [8]: 4000001b184aae7e) client | 2.216 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 386, tv_nsec: 604793205 } client | 2.216 DEBUG [Client e200c36dc13c6b41] pn=5 type=Short pri-path:4000001b184aae7e 0.0.0.0:53795->193.167.100.100:443 IpTos(Cs0, NotEct) len 46 client | TX -> Ack { largest_acknowledged: 12108055, ack_delay: 3834, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 2.216 DEBUG Setting timeout of 274.229894ms client | 2.216 DEBUG Setting timeout of 274.21656ms client | 2.492 DEBUG [Client e200c36dc13c6b41] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 386, tv_nsec: 879091778 } } -> Closed(Application(0)) client | 2.492 INFO Closing timer expired client | 2.492 DEBUG Output::None client | 2.492 WARN Unhandled event StateChange(Closed(Application(0))) client | 2.492 DEBUG Timer fired while closed client | 2.492 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 08:45:32,997 Using the client's key log file. 2025-04-30 08:45:33,009 2025-04-30 08:45:33,010 Using the client's key log file. 2025-04-30 08:45:33,010 Using the client's key log file. 2025-04-30 08:45:33,020 2025-04-30 08:45:33,021 Using the client's key log file. 2025-04-30 08:45:33,021 Using the client's key log file. 2025-04-30 08:45:33,663 Check of downloaded files succeeded. 2025-04-30 08:45:33,986 Server sent 9956 bytes in Handshake CRYPTO frames. 2025-04-30 08:45:34,355 Received a 1252 byte Initial packet from the client. Amplification limit: 3756 2025-04-30 08:45:34,356 Received a 1252 byte Initial packet from the client. Amplification limit: 7512 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 1232 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 2464 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 3696 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 4928 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 6160 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 7392 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 8624 2025-04-30 08:45:34,356 Server violated the amplification limit, but stayed within 3-4x amplification. Letting it slide. 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 9856 2025-04-30 08:45:34,356 Server violated the amplification limit, but stayed within 3-4x amplification. Letting it slide. 2025-04-30 08:45:34,356 Received a 1232 byte Handshake packet from the server. Total: 11088 2025-04-30 08:45:34,356 Server violated the amplification limit.