2025-04-30 08:47:08,715 Generated random file: eyajaqckyb of size: 1024 2025-04-30 08:47:08,715 Generated random file: aqpchymcfo of size: 1024 2025-04-30 08:47:08,715 Generated random file: fuwstfiepu of size: 1024 2025-04-30 08:47:08,715 Generated random file: dppwsupjay of size: 1024 2025-04-30 08:47:08,716 Generated random file: qcgplozldg of size: 1024 2025-04-30 08:47:08,716 Generated random file: jnwxiaawda of size: 1024 2025-04-30 08:47:08,716 Generated random file: yksekmhuhp of size: 1024 2025-04-30 08:47:08,716 Generated random file: rbythhqsob of size: 1024 2025-04-30 08:47:08,716 Generated random file: bystwktywb of size: 1024 2025-04-30 08:47:08,716 Generated random file: nfuwyeiovb of size: 1024 2025-04-30 08:47:08,716 Generated random file: yblzzkdaxg of size: 1024 2025-04-30 08:47:08,716 Generated random file: ojlbxyuyde of size: 1024 2025-04-30 08:47:08,717 Generated random file: vxenkbhxvq of size: 1024 2025-04-30 08:47:08,717 Generated random file: znacyxtgaf of size: 1024 2025-04-30 08:47:08,717 Generated random file: cvvicausyw of size: 1024 2025-04-30 08:47:08,717 Generated random file: axyihfevhw of size: 1024 2025-04-30 08:47:08,717 Generated random file: bjxebcflmo of size: 1024 2025-04-30 08:47:08,717 Generated random file: njzliexvxt of size: 1024 2025-04-30 08:47:08,717 Generated random file: zdgujcdsyn of size: 1024 2025-04-30 08:47:08,717 Generated random file: tufnngaiys of size: 1024 2025-04-30 08:47:08,717 Generated random file: fordiihzgv of size: 1024 2025-04-30 08:47:08,718 Generated random file: vfrnbtvsrd of size: 1024 2025-04-30 08:47:08,718 Generated random file: fqnaxthrod of size: 1024 2025-04-30 08:47:08,718 Generated random file: knxfzahzwj of size: 1024 2025-04-30 08:47:08,718 Generated random file: sljtqqwdwo of size: 1024 2025-04-30 08:47:08,718 Generated random file: gsaierhqhp of size: 1024 2025-04-30 08:47:08,718 Generated random file: khixvxvcwi of size: 1024 2025-04-30 08:47:08,718 Generated random file: zjgiuyafiw of size: 1024 2025-04-30 08:47:08,718 Generated random file: fhnipfaean of size: 1024 2025-04-30 08:47:08,718 Generated random file: rgaqglhusy of size: 1024 2025-04-30 08:47:08,719 Generated random file: bbyoerutzj of size: 1024 2025-04-30 08:47:08,719 Generated random file: feescdwvna of size: 1024 2025-04-30 08:47:08,719 Generated random file: qqmwayyiwl of size: 1024 2025-04-30 08:47:08,719 Generated random file: mxbtcknttq of size: 1024 2025-04-30 08:47:08,719 Generated random file: qqvipimrim of size: 1024 2025-04-30 08:47:08,719 Generated random file: nmwvrdbvpj of size: 1024 2025-04-30 08:47:08,719 Generated random file: ydxypcbsgb of size: 1024 2025-04-30 08:47:08,719 Generated random file: wbwsdpvuvu of size: 1024 2025-04-30 08:47:08,720 Generated random file: oqtjadkbyv of size: 1024 2025-04-30 08:47:08,720 Generated random file: chssjcevzr of size: 1024 2025-04-30 08:47:08,720 Generated random file: vhdqarvoia of size: 1024 2025-04-30 08:47:08,720 Generated random file: ftcbxspxub of size: 1024 2025-04-30 08:47:08,720 Generated random file: gzrfiofcqf of size: 1024 2025-04-30 08:47:08,720 Generated random file: xaediauzwg of size: 1024 2025-04-30 08:47:08,720 Generated random file: yzboddxqph of size: 1024 2025-04-30 08:47:08,720 Generated random file: gmysbvrojl of size: 1024 2025-04-30 08:47:08,720 Generated random file: nwrqkuhvsa of size: 1024 2025-04-30 08:47:08,721 Generated random file: qwncjkzaje of size: 1024 2025-04-30 08:47:08,721 Generated random file: zmofbwixhe of size: 1024 2025-04-30 08:47:08,721 Generated random file: oeipmhdaus of size: 1024 2025-04-30 08:47:08,721 Requests: https://server4:443/eyajaqckyb https://server4:443/aqpchymcfo https://server4:443/fuwstfiepu https://server4:443/dppwsupjay https://server4:443/qcgplozldg https://server4:443/jnwxiaawda https://server4:443/yksekmhuhp https://server4:443/rbythhqsob https://server4:443/bystwktywb https://server4:443/nfuwyeiovb https://server4:443/yblzzkdaxg https://server4:443/ojlbxyuyde https://server4:443/vxenkbhxvq https://server4:443/znacyxtgaf https://server4:443/cvvicausyw https://server4:443/axyihfevhw https://server4:443/bjxebcflmo https://server4:443/njzliexvxt https://server4:443/zdgujcdsyn https://server4:443/tufnngaiys https://server4:443/fordiihzgv https://server4:443/vfrnbtvsrd https://server4:443/fqnaxthrod https://server4:443/knxfzahzwj https://server4:443/sljtqqwdwo https://server4:443/gsaierhqhp https://server4:443/khixvxvcwi https://server4:443/zjgiuyafiw https://server4:443/fhnipfaean https://server4:443/rgaqglhusy https://server4:443/bbyoerutzj https://server4:443/feescdwvna https://server4:443/qqmwayyiwl https://server4:443/mxbtcknttq https://server4:443/qqvipimrim https://server4:443/nmwvrdbvpj https://server4:443/ydxypcbsgb https://server4:443/wbwsdpvuvu https://server4:443/oqtjadkbyv https://server4:443/chssjcevzr https://server4:443/vhdqarvoia https://server4:443/ftcbxspxub https://server4:443/gzrfiofcqf https://server4:443/xaediauzwg https://server4:443/yzboddxqph https://server4:443/gmysbvrojl https://server4:443/nwrqkuhvsa https://server4:443/qwncjkzaje https://server4:443/zmofbwixhe https://server4:443/oeipmhdaus 2025-04-30 08:47:08,783 2025-04-30 08:47:08,784 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_3d80povk/ TESTCASE_SERVER=multiconnect TESTCASE_CLIENT=multiconnect WWW=/tmp/www_dw46a07q/ DOWNLOADS=/tmp/download_0xobwydv/ SERVER_LOGS=/tmp/logs_server__pjk4lgh CLIENT_LOGS=/tmp/logs_client_h92ppv1s SCENARIO="corrupt-rate --delay=15ms --bandwidth=10Mbps --queue=25 --rate_to_server=30 --rate_to_client=30 --burst_to_server=3 --burst_to_client=3" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/facebook/proxygen/mvfst-interop:latest REQUESTS="https://server4:443/eyajaqckyb https://server4:443/aqpchymcfo https://server4:443/fuwstfiepu https://server4:443/dppwsupjay https://server4:443/qcgplozldg https://server4:443/jnwxiaawda https://server4:443/yksekmhuhp https://server4:443/rbythhqsob https://server4:443/bystwktywb https://server4:443/nfuwyeiovb https://server4:443/yblzzkdaxg https://server4:443/ojlbxyuyde https://server4:443/vxenkbhxvq https://server4:443/znacyxtgaf https://server4:443/cvvicausyw https://server4:443/axyihfevhw https://server4:443/bjxebcflmo https://server4:443/njzliexvxt https://server4:443/zdgujcdsyn https://server4:443/tufnngaiys https://server4:443/fordiihzgv https://server4:443/vfrnbtvsrd https://server4:443/fqnaxthrod https://server4:443/knxfzahzwj https://server4:443/sljtqqwdwo https://server4:443/gsaierhqhp https://server4:443/khixvxvcwi https://server4:443/zjgiuyafiw https://server4:443/fhnipfaean https://server4:443/rgaqglhusy https://server4:443/bbyoerutzj https://server4:443/feescdwvna https://server4:443/qqmwayyiwl https://server4:443/mxbtcknttq https://server4:443/qqvipimrim https://server4:443/nmwvrdbvpj https://server4:443/ydxypcbsgb https://server4:443/wbwsdpvuvu https://server4:443/oqtjadkbyv https://server4:443/chssjcevzr https://server4:443/vhdqarvoia https://server4:443/ftcbxspxub https://server4:443/gzrfiofcqf https://server4:443/xaediauzwg https://server4:443/yzboddxqph https://server4:443/gmysbvrojl https://server4:443/nwrqkuhvsa https://server4:443/qwncjkzaje https://server4:443/zmofbwixhe https://server4:443/oeipmhdaus" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 08:48:01,338 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 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 | + 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 | ++ awk -F / '{ print "/" $4 }' server | ++ tr ' ' '\n' server | ++ paste -sd, client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | + INVOCATIONS= server | + EARLYDATA=false server | + PSK_FILE= server | + '[' -n multiconnect ']' server | + case "${TESTCASE}" in 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=107374182 --stream_flow_control=107374182 --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 | + 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 multiconnect ']' client | + '[' -n /logs/qlog/ ']' client | + case "$ROLE" in client | + /wait-for-it.sh sim:57832 -s -t 30 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | W20250430 08:47:09.420672 25 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:47:09.420733 25 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:47:09.420738 25 QuicServerWorker.cpp:1234] HostId is already set to 0 server | W20250430 08:47:09.420745 25 QuicServerWorker.cpp:1234] HostId is already set to 0 server | I20250430 08:47:09.421445 25 HQServer.cpp:283] HQ server started at: [::]:443 sim | server:443 is available after 1.020077626s sim | Using scenario: corrupt-rate --delay=15ms --bandwidth=10Mbps --queue=25 --rate_to_server=30 --rate_to_client=30 --burst_to_server=3 --burst_to_client=3 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/eyajaqckyb https://server4:443/aqpchymcfo https://server4:443/fuwstfiepu https://server4:443/dppwsupjay https://server4:443/qcgplozldg https://server4:443/jnwxiaawda https://server4:443/yksekmhuhp https://server4:443/rbythhqsob https://server4:443/bystwktywb https://server4:443/nfuwyeiovb https://server4:443/yblzzkdaxg https://server4:443/ojlbxyuyde https://server4:443/vxenkbhxvq https://server4:443/znacyxtgaf https://server4:443/cvvicausyw https://server4:443/axyihfevhw https://server4:443/bjxebcflmo https://server4:443/njzliexvxt https://server4:443/zdgujcdsyn https://server4:443/tufnngaiys https://server4:443/fordiihzgv https://server4:443/vfrnbtvsrd https://server4:443/fqnaxthrod https://server4:443/knxfzahzwj https://server4:443/sljtqqwdwo https://server4:443/gsaierhqhp https://server4:443/khixvxvcwi https://server4:443/zjgiuyafiw https://server4:443/fhnipfaean https://server4:443/rgaqglhusy https://server4:443/bbyoerutzj https://server4:443/feescdwvna https://server4:443/qqmwayyiwl https://server4:443/mxbtcknttq https://server4:443/qqvipimrim https://server4:443/nmwvrdbvpj https://server4:443/ydxypcbsgb https://server4:443/wbwsdpvuvu https://server4:443/oqtjadkbyv https://server4:443/chssjcevzr https://server4:443/vhdqarvoia https://server4:443/ftcbxspxub https://server4:443/gzrfiofcqf https://server4:443/xaediauzwg https://server4:443/yzboddxqph https://server4:443/gmysbvrojl https://server4:443/nwrqkuhvsa https://server4:443/qwncjkzaje https://server4:443/zmofbwixhe https://server4:443/oeipmhdaus' ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test multiconnect --qlog-dir /logs/qlog/ --output-dir /downloads https://server4:443/eyajaqckyb https://server4:443/aqpchymcfo https://server4:443/fuwstfiepu https://server4:443/dppwsupjay https://server4:443/qcgplozldg https://server4:443/jnwxiaawda https://server4:443/yksekmhuhp https://server4:443/rbythhqsob https://server4:443/bystwktywb https://server4:443/nfuwyeiovb https://server4:443/yblzzkdaxg https://server4:443/ojlbxyuyde https://server4:443/vxenkbhxvq https://server4:443/znacyxtgaf https://server4:443/cvvicausyw https://server4:443/axyihfevhw https://server4:443/bjxebcflmo https://server4:443/njzliexvxt https://server4:443/zdgujcdsyn https://server4:443/tufnngaiys https://server4:443/fordiihzgv https://server4:443/vfrnbtvsrd https://server4:443/fqnaxthrod https://server4:443/knxfzahzwj https://server4:443/sljtqqwdwo https://server4:443/gsaierhqhp https://server4:443/khixvxvcwi https://server4:443/zjgiuyafiw https://server4:443/fhnipfaean https://server4:443/rgaqglhusy https://server4:443/bbyoerutzj https://server4:443/feescdwvna https://server4:443/qqmwayyiwl https://server4:443/mxbtcknttq https://server4:443/qqvipimrim https://server4:443/nmwvrdbvpj https://server4:443/ydxypcbsgb https://server4:443/wbwsdpvuvu https://server4:443/oqtjadkbyv https://server4:443/chssjcevzr https://server4:443/vhdqarvoia https://server4:443/ftcbxspxub https://server4:443/gzrfiofcqf https://server4:443/xaediauzwg https://server4:443/yzboddxqph https://server4:443/gmysbvrojl https://server4:443/nwrqkuhvsa https://server4:443/qwncjkzaje https://server4:443/zmofbwixhe 'https://server4:443/oeipmhdaus 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:53285 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=3ceacc95338730efb4 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:53285->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443] Path validated Instant { tv_sec: 494, tv_nsec: 761843476 } client | 0.001 DEBUG [Client 3ceacc95338730efb4] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x5576ebdc42d0] state -> InProgress client | 0.002 DEBUG [Client 3ceacc95338730efb4] State change from Init -> WaitInitial client | 0.002 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1212 client | 0.002 DEBUG [Client 3ceacc95338730efb4] pn=0 type=Initial pri-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1212 } client | 0.002 DEBUG packet_sent this=0x5576ebdd7be0, pn=0, ps=1252 client | 0.002 DEBUG ECN probing: sent 1 probes client | 0.002 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1212, len=327 client | 0.002 DEBUG [Client 3ceacc95338730efb4] pn=1 type=Initial pri-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 368 client | TX -> Crypto { offset: 1212, len: 327 } client | 0.002 DEBUG [Client 3ceacc95338730efb4] pad Initial from 368 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x5576ebdd7be0, pn=1, ps=1252 client | 0.002 DEBUG ECN probing: sent 2 probes client | 0.002 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) 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 3ceacc95338730efb4] delay duration 3.886675ms client | 0.002 DEBUG Setting timeout of 3.886675ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) 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 3ceacc95338730efb4] delay duration 3.858762ms client | 0.002 DEBUG Setting timeout of 3.858762ms client | 0.007 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) 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 3ceacc95338730efb4] delay duration 294.792453ms client | 0.007 DEBUG Setting timeout of 294.792453ms sim | Corrupting 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443 offset 11 0x87 -> 0x54, corrupted 1/1 (100.0%) sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 1/2 (50.0%) client | 0.303 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.303 DEBUG [Client 3ceacc95338730efb4] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1212 }) client | 0.303 INFO Lost crypto frame space=in offset=0 length=1212 client | 0.303 DEBUG [Client 3ceacc95338730efb4] Lost: EcnEct0 client | 0.303 DEBUG [Client 3ceacc95338730efb4] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 327 }) client | 0.303 INFO Lost crypto frame space=in offset=1212 length=327 client | 0.303 DEBUG [Client 3ceacc95338730efb4] Lost: EcnEct0 client | 0.303 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.303 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) scid Some(CID [0]: ) client | 0.303 DEBUG CRYPTO for in offset=0, len=1212 client | 0.303 DEBUG [Client 3ceacc95338730efb4] pn=2 type=Initial pri-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1212 } client | 0.303 DEBUG packet_sent this=0x5576ebdd7be0, pn=2, ps=1252 client | 0.303 DEBUG ECN probing: sent 3 probes client | 0.304 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.304 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) scid Some(CID [0]: ) client | 0.304 DEBUG CRYPTO for in offset=1212, len=327 client | 0.304 DEBUG [Client 3ceacc95338730efb4] pn=3 type=Initial pri-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 368 client | TX -> Crypto { offset: 1212, len: 327 } client | 0.304 DEBUG [Client 3ceacc95338730efb4] pad Initial from 368 to PLPMTU 1252 client | 0.304 DEBUG packet_sent this=0x5576ebdd7be0, pn=3, ps=1252 client | 0.304 DEBUG ECN probing: sent 4 probes client | 0.304 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.304 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) 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 3ceacc95338730efb4] delay duration 599.921242ms client | 0.304 DEBUG Setting timeout of 599.921242ms sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 1/3 (33.3%) sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 1/4 (25.0%) sim | Corrupting 1232 bytes 193.167.100.100:443 -> 193.167.0.100:53285 offset 50 0x14 -> 0x64, corrupted 1/1 (100.0%) sim | Forwarding 754 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 1/2 (50.0%) client | 0.337 DEBUG saving datagram of 754 bytes client | 0.337 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.337 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) scid Some(CID [0]: ) client | 0.337 DEBUG CRYPTO for in offset=0, len=1212 client | 0.337 DEBUG [Client 3ceacc95338730efb4] pn=4 type=Initial pri-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1212 } client | 0.337 DEBUG packet_sent this=0x5576ebdd7be0, pn=4, ps=1252 client | 0.337 DEBUG ECN probing: sent 5 probes client | 0.338 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.338 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) scid Some(CID [0]: ) client | 0.338 DEBUG CRYPTO for in offset=1212, len=327 client | 0.338 DEBUG [Client 3ceacc95338730efb4] pn=5 type=Initial pri-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 368 client | TX -> Crypto { offset: 1212, len: 327 } client | 0.338 DEBUG [Client 3ceacc95338730efb4] pad Initial from 368 to PLPMTU 1252 client | 0.338 DEBUG packet_sent this=0x5576ebdd7be0, pn=5, ps=1252 client | 0.338 DEBUG ECN probing: sent 6 probes client | 0.338 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.338 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) scid Some(CID [0]: ) client | 0.338 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.338 DEBUG [Client 3ceacc95338730efb4] delay duration 599.905503ms client | 0.338 DEBUG Setting timeout of 599.905503ms client | 0.338 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.338 DEBUG Building Initial dcid Some(CID [9]: 3ceacc95338730efb4) scid Some(CID [0]: ) client | 0.338 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.338 DEBUG [Client 3ceacc95338730efb4] delay duration 599.875447ms client | 0.338 DEBUG Setting timeout of 599.875447ms sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 1/5 (20.0%) sim | Corrupting 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443 offset 12 0x30 -> 0xda, corrupted 2/6 (33.3%) sim | Forwarding 1232 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 1/3 (33.3%) client | 0.451 DEBUG [Client 3ceacc95338730efb4] pn=5627719 type=Initial pri-path:3ceacc95338730efb4 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Ack { largest_acknowledged: 4, ack_delay: 10044, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | -> RX Padding { len: 1189 } client | 0.451 DEBUG [Client 3ceacc95338730efb4] Rx ACK space=in, ranges=[1..=4] client | 0.451 DEBUG [LossRecovery] ACK for Initial - largest_acked=4 client | 0.451 DEBUG packet_lost this=0x5576ebdd7be0, pn=0, ps=1252 client | 0.451 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6260/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764 client | 0.451 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6260/8764 ssthresh 8764] state -> RecoveryStart client | 0.451 DEBUG on_packets_lost this=0x5576ebdd7be0, bytes_in_flight=6260, cwnd=8764, state=RecoveryStart client | 0.451 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 495, tv_nsec: 211682991 })] CongCtrl 1252/8764 ssthresh 8764] on_packets_acked this=0x5576ebdd7be0, limited=0, bytes_in_flight=1252, cwnd=8764, state=RecoveryStart, new_acked=0 client | 0.451 DEBUG Acked crypto frame space=in offset=0 length=1212 client | 0.451 DEBUG Acked crypto frame space=in offset=1212 length=327 client | 0.451 DEBUG Acked crypto frame space=in offset=0 length=1212 client | 0.451 DEBUG Acked crypto frame space=in offset=1212 length=327 client | 0.451 DEBUG [Client 3ceacc95338730efb4] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1212 }) client | 0.451 INFO Lost crypto frame space=in offset=0 length=1212 client | 0.451 DEBUG [Client 3ceacc95338730efb4] Lost: EcnEct0 client | 0.451 DEBUG [Client 3ceacc95338730efb4] Changing to use Server CID=400000d1f09b7e94 client | 0.451 DEBUG [Client 3ceacc95338730efb4] State change from WaitInitial -> WaitVersion client | 0.451 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.451 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 0.451 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.451 DEBUG [Client 3ceacc95338730efb4] delay duration 226.146932ms client | 0.451 DEBUG Setting timeout of 226.146932ms client | 0.451 WARN Unhandled event StateChange(WaitVersion) client | 0.451 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.451 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 0.451 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.451 DEBUG [Client 3ceacc95338730efb4] delay duration 226.107258ms client | 0.451 DEBUG Setting timeout of 226.107258ms sim | Forwarding 754 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 1/4 (25.0%) client | 0.451 DEBUG saving datagram of 754 bytes client | 0.451 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.451 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 0.451 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.451 DEBUG [Client 3ceacc95338730efb4] delay duration 225.755796ms client | 0.451 DEBUG Setting timeout of 225.755796ms client | 0.451 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.451 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 0.451 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.451 DEBUG [Client 3ceacc95338730efb4] delay duration 225.732322ms client | 0.451 DEBUG Setting timeout of 225.732322ms sim | Corrupting 1232 bytes 193.167.100.100:443 -> 193.167.0.100:53285 offset 9 0x0 -> 0xe6, corrupted 2/5 (40.0%) sim | Corrupting 754 bytes 193.167.100.100:443 -> 193.167.0.100:53285 offset 21 0xc8 -> 0xca, corrupted 3/6 (50.0%) client | 0.677 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.677 DEBUG [Client 3ceacc95338730efb4] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 327 }) client | 0.677 INFO Lost crypto frame space=in offset=1212 length=327 client | 0.677 DEBUG [Client 3ceacc95338730efb4] Lost: EcnEct0 client | 0.677 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.677 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 0.677 DEBUG [Client 3ceacc95338730efb4] pn=6 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 36 client | TX -> Ping client | 0.677 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 0.677 DEBUG packet_sent this=0x5576ebdd7be0, pn=6, ps=1252 client | 0.677 DEBUG ECN probing: sent 7 probes client | 0.678 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.678 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 0.678 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.678 DEBUG [Client 3ceacc95338730efb4] delay duration 678.472332ms client | 0.678 DEBUG Setting timeout of 678.472332ms sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 2/7 (28.6%) sim | Forwarding 1232 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 3/7 (42.9%) client | 0.710 DEBUG [Client 3ceacc95338730efb4] pn=5627722 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Ack { largest_acknowledged: 6, ack_delay: 22, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 3 }], ecn_count: None } client | -> RX Padding { len: 1188 } client | 0.710 DEBUG [Client 3ceacc95338730efb4] Rx ACK space=in, ranges=[6..=6, 1..=4] client | 0.710 DEBUG [LossRecovery] ACK for Initial - largest_acked=6 client | 0.710 DEBUG packet_lost this=0x5576ebdd7be0, pn=5, ps=1252 client | 0.710 DEBUG on_packets_lost this=0x5576ebdd7be0, bytes_in_flight=1252, cwnd=8764, state=Recovery client | 0.710 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 495, tv_nsec: 211682991 })] CongCtrl 0/8764 ssthresh 8764] state -> CongestionAvoidance client | 0.710 DEBUG on_packets_acked this=0x5576ebdd7be0, limited=1, bytes_in_flight=0, cwnd=8764, state=CongestionAvoidance, new_acked=1252 client | 0.710 DEBUG [Client 3ceacc95338730efb4] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1212, length: 327 }) client | 0.710 INFO Lost crypto frame space=in offset=1212 length=327 client | 0.710 DEBUG [Client 3ceacc95338730efb4] Lost: EcnEct0 client | 0.710 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.710 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 0.710 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.710 DEBUG [Client 3ceacc95338730efb4] delay duration 320.114664ms client | 0.710 DEBUG Setting timeout of 320.114664ms client | 0.710 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.710 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 0.710 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.710 DEBUG [Client 3ceacc95338730efb4] delay duration 320.088344ms client | 0.710 DEBUG Setting timeout of 320.088344ms client | 1.032 DEBUG [LossRecovery] PTO timer fired for Initial client | 1.032 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 1.032 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.032 DEBUG [Client 3ceacc95338730efb4] pn=7 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 36 client | TX -> Ping client | 1.032 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 1.032 DEBUG packet_sent this=0x5576ebdd7be0, pn=7, ps=1252 client | 1.032 DEBUG ECN probing: sent 8 probes client | 1.032 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.032 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.032 DEBUG [Client 3ceacc95338730efb4] delay duration 705.856853ms client | 1.032 DEBUG Setting timeout of 705.856853ms sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 2/8 (25.0%) sim | Forwarding 1232 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 3/8 (37.5%) client | 1.081 DEBUG [Client 3ceacc95338730efb4] pn=5627724 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Ack { largest_acknowledged: 7, ack_delay: 2024, first_ack_range: 1, ack_ranges: [AckRange { gap: 0, range: 3 }], ecn_count: None } client | -> RX Padding { len: 1187 } client | 1.081 DEBUG [Client 3ceacc95338730efb4] Rx ACK space=in, ranges=[6..=7, 1..=4] client | 1.081 DEBUG [LossRecovery] ACK for Initial - largest_acked=7 client | 1.081 DEBUG on_packets_acked this=0x5576ebdd7be0, limited=1, bytes_in_flight=0, cwnd=8764, state=CongestionAvoidance, new_acked=1252 client | 1.081 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.081 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.081 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.081 DEBUG [Client 3ceacc95338730efb4] delay duration 288.925643ms client | 1.081 DEBUG Setting timeout of 288.925643ms client | 1.081 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.081 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.081 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.081 DEBUG [Client 3ceacc95338730efb4] delay duration 288.866352ms client | 1.081 DEBUG Setting timeout of 288.866352ms sim | Corrupting 754 bytes 193.167.100.100:443 -> 193.167.0.100:53285 offset 47 0xcb -> 0x78, corrupted 4/9 (44.4%) client | 1.371 DEBUG [LossRecovery] PTO timer fired for Initial client | 1.371 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 1.371 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.371 DEBUG [Client 3ceacc95338730efb4] pn=8 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 36 client | TX -> Ping client | 1.371 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 1.371 DEBUG packet_sent this=0x5576ebdd7be0, pn=8, ps=1252 client | 1.371 DEBUG ECN probing: sent 9 probes client | 1.371 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.371 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.371 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.371 DEBUG [Client 3ceacc95338730efb4] delay duration 675.655308ms client | 1.371 DEBUG Setting timeout of 675.655308ms sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 2/9 (22.2%) sim | Forwarding 1232 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 4/10 (40.0%) client | 1.895 DEBUG [Client 3ceacc95338730efb4] pn=5627726 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Ack { largest_acknowledged: 8, ack_delay: 61354, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 3 }], ecn_count: None } client | -> RX Padding { len: 1185 } client | 1.895 DEBUG [Client 3ceacc95338730efb4] Rx ACK space=in, ranges=[6..=8, 1..=4] client | 1.895 DEBUG [LossRecovery] ACK for Initial - largest_acked=8 client | 1.895 DEBUG on_packets_acked this=0x5576ebdd7be0, limited=1, bytes_in_flight=0, cwnd=8764, state=CongestionAvoidance, new_acked=1252 client | 1.895 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.895 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.895 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.895 DEBUG [Client 3ceacc95338730efb4] delay duration 234.525408ms client | 1.895 DEBUG Setting timeout of 234.525408ms client | 1.895 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.895 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.895 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.895 DEBUG [Client 3ceacc95338730efb4] delay duration 234.488238ms client | 1.895 DEBUG Setting timeout of 234.488238ms sim | Forwarding 754 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 4/11 (36.4%) client | 1.896 DEBUG saving datagram of 754 bytes client | 1.896 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.896 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.896 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.896 DEBUG [Client 3ceacc95338730efb4] delay duration 234.069024ms client | 1.896 DEBUG Setting timeout of 234.069024ms client | 1.896 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.896 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 1.896 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.896 DEBUG [Client 3ceacc95338730efb4] delay duration 234.049468ms client | 1.896 DEBUG Setting timeout of 234.049468ms client | 2.131 DEBUG [LossRecovery] PTO timer fired for Initial client | 2.131 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 2.131 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 2.131 DEBUG [Client 3ceacc95338730efb4] pn=9 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, Ect0) len 36 client | TX -> Ping client | 2.131 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 2.131 DEBUG packet_sent this=0x5576ebdd7be0, pn=9, ps=1252 client | 2.131 DEBUG ECN probing: sent 10 probes client | 2.131 DEBUG ECN probing concluded with 10 probes sent client | 2.131 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.131 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 2.131 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.131 DEBUG [Client 3ceacc95338730efb4] delay duration 1.516532401s client | 2.131 DEBUG Setting timeout of 1.516532401s sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 2/10 (20.0%) sim | Corrupting 1232 bytes 193.167.100.100:443 -> 193.167.0.100:53285 offset 16 0x44 -> 0x51, corrupted 5/12 (41.7%) sim | Corrupting 754 bytes 193.167.100.100:443 -> 193.167.0.100:53285 offset 13 0x7e -> 0x80, corrupted 6/13 (46.2%) client | 3.650 DEBUG [LossRecovery] PTO timer fired for Initial client | 3.650 DEBUG [Client 3ceacc95338730efb4] Lost: EcnEct0 client | 3.650 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 3.650 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 3.650 DEBUG [Client 3ceacc95338730efb4] pn=10 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 36 client | TX -> Ping client | 3.650 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 3.650 DEBUG packet_sent this=0x5576ebdd7be0, pn=10, ps=1252 client | 3.650 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.650 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 3.650 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.650 DEBUG [Client 3ceacc95338730efb4] delay duration 3.033237906s client | 3.650 DEBUG Setting timeout of 3.033237906s sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 2/11 (18.2%) client | 6.684 DEBUG [LossRecovery] PTO timer fired for Initial client | 6.684 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 6.684 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 6.684 DEBUG [Client 3ceacc95338730efb4] pn=11 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 36 client | TX -> Ping client | 6.684 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 6.684 DEBUG packet_sent this=0x5576ebdd7be0, pn=11, ps=1252 client | 6.684 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.684 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 6.684 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.684 DEBUG [Client 3ceacc95338730efb4] delay duration 6.066716122s client | 6.684 DEBUG Setting timeout of 6.066716122s sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 2/12 (16.7%) sim | Forwarding 1232 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 6/14 (42.9%) client | 6.715 DEBUG [Client 3ceacc95338730efb4] pn=5627730 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232 client | -> RX Ack { largest_acknowledged: 10, ack_delay: 379081, first_ack_range: 4, ack_ranges: [AckRange { gap: 0, range: 3 }], ecn_count: None } client | -> RX Padding { len: 1185 } client | 6.715 DEBUG [Client 3ceacc95338730efb4] Rx ACK space=in, ranges=[6..=10, 1..=4] client | 6.715 DEBUG [LossRecovery] ACK for Initial - largest_acked=10 client | 6.715 WARN ECN validation failed, no ECN counts in ACK frame client | 6.715 DEBUG on_packets_acked this=0x5576ebdd7be0, limited=1, bytes_in_flight=1252, cwnd=8764, state=CongestionAvoidance, new_acked=2504 client | 6.715 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.715 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 6.715 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.715 DEBUG [Client 3ceacc95338730efb4] delay duration 3.855288001s client | 6.715 DEBUG Setting timeout of 3.855288001s client | 6.715 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.715 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 6.715 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.715 DEBUG [Client 3ceacc95338730efb4] delay duration 3.8552609s client | 6.715 DEBUG Setting timeout of 3.8552609s sim | Forwarding 754 bytes 193.167.100.100:443 -> 193.167.0.100:53285, corrupted 6/15 (40.0%) client | 6.716 DEBUG saving datagram of 754 bytes client | 6.716 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.716 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 6.716 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.716 DEBUG [Client 3ceacc95338730efb4] delay duration 3.854761945s client | 6.716 DEBUG Setting timeout of 3.854761945s client | 6.716 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.716 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 6.716 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.716 DEBUG [Client 3ceacc95338730efb4] delay duration 3.854737569s client | 6.716 DEBUG Setting timeout of 3.854737569s client | 10.572 DEBUG [LossRecovery] PTO timer fired for Initial client | 10.572 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 10.572 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 10.572 DEBUG [Client 3ceacc95338730efb4] pn=12 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 36 client | TX -> Ping client | 10.572 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 10.572 DEBUG packet_sent this=0x5576ebdd7be0, pn=12, ps=1252 client | 10.572 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 10.572 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 10.572 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 10.572 DEBUG [Client 3ceacc95338730efb4] delay duration 7.772654992s client | 10.572 DEBUG Setting timeout of 7.772654992s sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 2/13 (15.4%) server | I20250430 08:47:23.537601 35 H1QDownstreamSession.h:88] onConnectionError client | 18.346 DEBUG [LossRecovery] PTO timer fired for Initial client | 18.346 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 18.346 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 18.346 DEBUG [Client 3ceacc95338730efb4] pn=13 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 36 client | TX -> Ping client | 18.346 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 18.346 DEBUG packet_sent this=0x5576ebdd7be0, pn=13, ps=1252 client | 18.346 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 18.346 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 18.346 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 18.346 DEBUG [Client 3ceacc95338730efb4] delay duration 15.545627939s client | 18.346 DEBUG Setting timeout of 15.545627939s sim | Corrupting 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443 offset 13 0x94 -> 0x8f, corrupted 3/14 (21.4%) client | 33.893 DEBUG [LossRecovery] PTO timer fired for Initial client | 33.893 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 33.893 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 33.893 DEBUG [Client 3ceacc95338730efb4] pn=14 type=Initial pri-path:400000d1f09b7e94 0.0.0.0:53285->193.167.100.100:443 IpTos(Cs0, NotEct) len 36 client | TX -> Ping client | 33.893 DEBUG [Client 3ceacc95338730efb4] pad Initial from 38 to PLPMTU 1252 client | 33.893 DEBUG packet_sent this=0x5576ebdd7be0, pn=14, ps=1252 client | 33.893 DEBUG [Client 3ceacc95338730efb4] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 33.893 DEBUG Building Initial dcid Some(CID [8]: 400000d1f09b7e94) scid Some(CID [0]: ) client | 33.893 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 33.893 DEBUG [Client 3ceacc95338730efb4] delay duration 6.67861296s client | 33.893 DEBUG Setting timeout of 6.67861296s sim | Forwarding 1252 bytes 193.167.0.100:53285 -> 193.167.100.100:443, corrupted 3/15 (20.0%) server | E20250430 08:47:44.346014 35 HQServer.cpp:195] Failed to accept QUIC connection: Failed to decrypt first packet from peer client | 40.573 INFO [Client 3ceacc95338730efb4] idle timeout expired client | 40.573 DEBUG [Client 3ceacc95338730efb4] State change from WaitVersion -> Closed(Transport(IdleTimeout)) client | 40.573 DEBUG Output::None client | 40.573 WARN Unhandled event StateChange(Closed(Transport(IdleTimeout))) client | 40.573 DEBUG Timer fired while closed client | 40.573 DEBUG Output::None client | Error: TransportError(IdleTimeout) client exited with code 1 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped