2025-04-30 10:53:11,990 Generated random file: ettjxoyggo of size: 1024 2025-04-30 10:53:11,990 Generated random file: bqklknwytu of size: 1024 2025-04-30 10:53:11,991 Generated random file: bkzthnoyzm of size: 1024 2025-04-30 10:53:11,991 Generated random file: cknsljnpbk of size: 1024 2025-04-30 10:53:11,991 Generated random file: gcdewbibug of size: 1024 2025-04-30 10:53:11,991 Generated random file: wehrjniyyt of size: 1024 2025-04-30 10:53:11,991 Generated random file: exrtqzvvza of size: 1024 2025-04-30 10:53:11,991 Generated random file: aknfnhdbyo of size: 1024 2025-04-30 10:53:11,991 Generated random file: inyycdxxiq of size: 1024 2025-04-30 10:53:11,991 Generated random file: ukldfletqm of size: 1024 2025-04-30 10:53:11,991 Generated random file: jjnytdwhbf of size: 1024 2025-04-30 10:53:11,992 Generated random file: ihcjmhjmyk of size: 1024 2025-04-30 10:53:11,992 Generated random file: rehayoerro of size: 1024 2025-04-30 10:53:11,992 Generated random file: klvoomnkvg of size: 1024 2025-04-30 10:53:11,992 Generated random file: wijwgqvith of size: 1024 2025-04-30 10:53:11,992 Generated random file: awxdcuqmss of size: 1024 2025-04-30 10:53:11,992 Generated random file: xkqufucewa of size: 1024 2025-04-30 10:53:11,992 Generated random file: sgyltdirpe of size: 1024 2025-04-30 10:53:11,992 Generated random file: fdsiknnagg of size: 1024 2025-04-30 10:53:11,993 Generated random file: wrbaptmitb of size: 1024 2025-04-30 10:53:11,993 Generated random file: hbbmtssdut of size: 1024 2025-04-30 10:53:11,993 Generated random file: etophoesos of size: 1024 2025-04-30 10:53:11,993 Generated random file: spvbrwxdgh of size: 1024 2025-04-30 10:53:11,993 Generated random file: mlkqnzjurb of size: 1024 2025-04-30 10:53:11,993 Generated random file: mskhxmgroh of size: 1024 2025-04-30 10:53:11,993 Generated random file: mejkblabox of size: 1024 2025-04-30 10:53:11,993 Generated random file: zkqcellfyn of size: 1024 2025-04-30 10:53:11,993 Generated random file: cyzrwdhdsj of size: 1024 2025-04-30 10:53:11,994 Generated random file: wodfqnecye of size: 1024 2025-04-30 10:53:11,994 Generated random file: jlszqscjpz of size: 1024 2025-04-30 10:53:11,994 Generated random file: merqlgruyn of size: 1024 2025-04-30 10:53:11,994 Generated random file: fefpqtrtej of size: 1024 2025-04-30 10:53:11,994 Generated random file: hjzoyyquui of size: 1024 2025-04-30 10:53:11,994 Generated random file: uquqfjjzxg of size: 1024 2025-04-30 10:53:11,994 Generated random file: kbyrobffzt of size: 1024 2025-04-30 10:53:11,994 Generated random file: ddkujhgvld of size: 1024 2025-04-30 10:53:11,995 Generated random file: rhwgyzdzhn of size: 1024 2025-04-30 10:53:11,995 Generated random file: vrxbcaasyv of size: 1024 2025-04-30 10:53:11,995 Generated random file: ujscdezzcq of size: 1024 2025-04-30 10:53:11,995 Generated random file: ujfrpgugbi of size: 1024 2025-04-30 10:53:11,995 Generated random file: ggvzfpxhkg of size: 1024 2025-04-30 10:53:11,995 Generated random file: krczxxxidq of size: 1024 2025-04-30 10:53:11,995 Generated random file: dfgutpdfly of size: 1024 2025-04-30 10:53:11,995 Generated random file: hfmcgaiwep of size: 1024 2025-04-30 10:53:11,996 Generated random file: nmbsneyutl of size: 1024 2025-04-30 10:53:11,996 Generated random file: etkjisnycj of size: 1024 2025-04-30 10:53:11,996 Generated random file: pibmqtpzxp of size: 1024 2025-04-30 10:53:11,996 Generated random file: wslrfczqaj of size: 1024 2025-04-30 10:53:11,996 Generated random file: faczcbtjfm of size: 1024 2025-04-30 10:53:11,996 Generated random file: ayluzaypai of size: 1024 2025-04-30 10:53:11,996 Requests: https://server4:443/ettjxoyggo https://server4:443/bqklknwytu https://server4:443/bkzthnoyzm https://server4:443/cknsljnpbk https://server4:443/gcdewbibug https://server4:443/wehrjniyyt https://server4:443/exrtqzvvza https://server4:443/aknfnhdbyo https://server4:443/inyycdxxiq https://server4:443/ukldfletqm https://server4:443/jjnytdwhbf https://server4:443/ihcjmhjmyk https://server4:443/rehayoerro https://server4:443/klvoomnkvg https://server4:443/wijwgqvith https://server4:443/awxdcuqmss https://server4:443/xkqufucewa https://server4:443/sgyltdirpe https://server4:443/fdsiknnagg https://server4:443/wrbaptmitb https://server4:443/hbbmtssdut https://server4:443/etophoesos https://server4:443/spvbrwxdgh https://server4:443/mlkqnzjurb https://server4:443/mskhxmgroh https://server4:443/mejkblabox https://server4:443/zkqcellfyn https://server4:443/cyzrwdhdsj https://server4:443/wodfqnecye https://server4:443/jlszqscjpz https://server4:443/merqlgruyn https://server4:443/fefpqtrtej https://server4:443/hjzoyyquui https://server4:443/uquqfjjzxg https://server4:443/kbyrobffzt https://server4:443/ddkujhgvld https://server4:443/rhwgyzdzhn https://server4:443/vrxbcaasyv https://server4:443/ujscdezzcq https://server4:443/ujfrpgugbi https://server4:443/ggvzfpxhkg https://server4:443/krczxxxidq https://server4:443/dfgutpdfly https://server4:443/hfmcgaiwep https://server4:443/nmbsneyutl https://server4:443/etkjisnycj https://server4:443/pibmqtpzxp https://server4:443/wslrfczqaj https://server4:443/faczcbtjfm https://server4:443/ayluzaypai 2025-04-30 10:53:12,058 2025-04-30 10:53:12,059 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_7lfyfk3l/ TESTCASE_SERVER=multiconnect TESTCASE_CLIENT=multiconnect WWW=/tmp/www_7dfdkbkm/ DOWNLOADS=/tmp/download_qhky5u98/ SERVER_LOGS=/tmp/logs_server_2pvgugv8 CLIENT_LOGS=/tmp/logs_client_6_78kgsg 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=peterdoornbosch/kwik_n_flupke-interop REQUESTS="https://server4:443/ettjxoyggo https://server4:443/bqklknwytu https://server4:443/bkzthnoyzm https://server4:443/cknsljnpbk https://server4:443/gcdewbibug https://server4:443/wehrjniyyt https://server4:443/exrtqzvvza https://server4:443/aknfnhdbyo https://server4:443/inyycdxxiq https://server4:443/ukldfletqm https://server4:443/jjnytdwhbf https://server4:443/ihcjmhjmyk https://server4:443/rehayoerro https://server4:443/klvoomnkvg https://server4:443/wijwgqvith https://server4:443/awxdcuqmss https://server4:443/xkqufucewa https://server4:443/sgyltdirpe https://server4:443/fdsiknnagg https://server4:443/wrbaptmitb https://server4:443/hbbmtssdut https://server4:443/etophoesos https://server4:443/spvbrwxdgh https://server4:443/mlkqnzjurb https://server4:443/mskhxmgroh https://server4:443/mejkblabox https://server4:443/zkqcellfyn https://server4:443/cyzrwdhdsj https://server4:443/wodfqnecye https://server4:443/jlszqscjpz https://server4:443/merqlgruyn https://server4:443/fefpqtrtej https://server4:443/hjzoyyquui https://server4:443/uquqfjjzxg https://server4:443/kbyrobffzt https://server4:443/ddkujhgvld https://server4:443/rhwgyzdzhn https://server4:443/vrxbcaasyv https://server4:443/ujscdezzcq https://server4:443/ujfrpgugbi https://server4:443/ggvzfpxhkg https://server4:443/krczxxxidq https://server4:443/dfgutpdfly https://server4:443/hfmcgaiwep https://server4:443/nmbsneyutl https://server4:443/etkjisnycj https://server4:443/pibmqtpzxp https://server4:443/wslrfczqaj https://server4:443/faczcbtjfm https://server4:443/ayluzaypai" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 10:54:02,063 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... 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 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 client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + '[' -n 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 | Importing keystore /server.p12 to /server.keystore... server | running kwik server version 0.10.2 server | Old Flupke plugin not found sim | server:443 is available after 1.039620211s 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/ettjxoyggo https://server4:443/bqklknwytu https://server4:443/bkzthnoyzm https://server4:443/cknsljnpbk https://server4:443/gcdewbibug https://server4:443/wehrjniyyt https://server4:443/exrtqzvvza https://server4:443/aknfnhdbyo https://server4:443/inyycdxxiq https://server4:443/ukldfletqm https://server4:443/jjnytdwhbf https://server4:443/ihcjmhjmyk https://server4:443/rehayoerro https://server4:443/klvoomnkvg https://server4:443/wijwgqvith https://server4:443/awxdcuqmss https://server4:443/xkqufucewa https://server4:443/sgyltdirpe https://server4:443/fdsiknnagg https://server4:443/wrbaptmitb https://server4:443/hbbmtssdut https://server4:443/etophoesos https://server4:443/spvbrwxdgh https://server4:443/mlkqnzjurb https://server4:443/mskhxmgroh https://server4:443/mejkblabox https://server4:443/zkqcellfyn https://server4:443/cyzrwdhdsj https://server4:443/wodfqnecye https://server4:443/jlszqscjpz https://server4:443/merqlgruyn https://server4:443/fefpqtrtej https://server4:443/hjzoyyquui https://server4:443/uquqfjjzxg https://server4:443/kbyrobffzt https://server4:443/ddkujhgvld https://server4:443/rhwgyzdzhn https://server4:443/vrxbcaasyv https://server4:443/ujscdezzcq https://server4:443/ujfrpgugbi https://server4:443/ggvzfpxhkg https://server4:443/krczxxxidq https://server4:443/dfgutpdfly https://server4:443/hfmcgaiwep https://server4:443/nmbsneyutl https://server4:443/etkjisnycj https://server4:443/pibmqtpzxp https://server4:443/wslrfczqaj https://server4:443/faczcbtjfm https://server4:443/ayluzaypai' ']' 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/ettjxoyggo https://server4:443/bqklknwytu https://server4:443/bkzthnoyzm https://server4:443/cknsljnpbk https://server4:443/gcdewbibug https://server4:443/wehrjniyyt https://server4:443/exrtqzvvza https://server4:443/aknfnhdbyo https://server4:443/inyycdxxiq https://server4:443/ukldfletqm https://server4:443/jjnytdwhbf https://server4:443/ihcjmhjmyk https://server4:443/rehayoerro https://server4:443/klvoomnkvg https://server4:443/wijwgqvith https://server4:443/awxdcuqmss https://server4:443/xkqufucewa https://server4:443/sgyltdirpe https://server4:443/fdsiknnagg https://server4:443/wrbaptmitb https://server4:443/hbbmtssdut https://server4:443/etophoesos https://server4:443/spvbrwxdgh https://server4:443/mlkqnzjurb https://server4:443/mskhxmgroh https://server4:443/mejkblabox https://server4:443/zkqcellfyn https://server4:443/cyzrwdhdsj https://server4:443/wodfqnecye https://server4:443/jlszqscjpz https://server4:443/merqlgruyn https://server4:443/fefpqtrtej https://server4:443/hjzoyyquui https://server4:443/uquqfjjzxg https://server4:443/kbyrobffzt https://server4:443/ddkujhgvld https://server4:443/rhwgyzdzhn https://server4:443/vrxbcaasyv https://server4:443/ujscdezzcq https://server4:443/ujfrpgugbi https://server4:443/ggvzfpxhkg https://server4:443/krczxxxidq https://server4:443/dfgutpdfly https://server4:443/hfmcgaiwep https://server4:443/nmbsneyutl https://server4:443/etkjisnycj https://server4:443/pibmqtpzxp https://server4:443/wslrfczqaj https://server4:443/faczcbtjfm 'https://server4:443/ayluzaypai 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:53438 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=bfd3c0e1630ceb4b423ab70e5c 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:53438->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:bfd3c0e1630ceb4b423ab70e5c 0.0.0.0:53438->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:bfd3c0e1630ceb4b423ab70e5c 0.0.0.0:53438->193.167.100.100:443] Path validated Instant { tv_sec: 608, tv_nsec: 280673535 } client | 0.001 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] client_start client | 0.001 DEBUG Writing transport parameters, msg=1 client | 0.001 DEBUG [Agent 0x5560396062d0] state -> InProgress client | 0.001 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] State change from Init -> WaitInitial client | 0.001 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.001 DEBUG Building Initial dcid Some(CID [13]: bfd3c0e1630ceb4b423ab70e5c) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1208 client | 0.002 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=0 type=Initial pri-path:bfd3c0e1630ceb4b423ab70e5c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 0.002 DEBUG packet_sent this=0x556039619be0, pn=0, ps=1252 client | 0.002 DEBUG ECN probing: sent 1 probes client | 0.002 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: bfd3c0e1630ceb4b423ab70e5c) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1208, len=331 client | 0.002 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=1 type=Initial pri-path:bfd3c0e1630ceb4b423ab70e5c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 376 client | TX -> Crypto { offset: 1208, len: 331 } client | 0.002 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pad Initial from 376 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x556039619be0, pn=1, ps=1252 client | 0.002 DEBUG ECN probing: sent 2 probes client | 0.002 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: bfd3c0e1630ceb4b423ab70e5c) 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 bfd3c0e1630ceb4b423ab70e5c] delay duration 3.890033ms client | 0.002 DEBUG Setting timeout of 3.890033ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: bfd3c0e1630ceb4b423ab70e5c) 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 bfd3c0e1630ceb4b423ab70e5c] delay duration 3.865597ms client | 0.002 DEBUG Setting timeout of 3.865597ms client | 0.007 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [13]: bfd3c0e1630ceb4b423ab70e5c) 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 bfd3c0e1630ceb4b423ab70e5c] delay duration 294.818069ms client | 0.007 DEBUG Setting timeout of 294.818069ms sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 0/1 (0.0%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 0/2 (0.0%) sim | Corrupting 1201 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 44 0x64 -> 0x7d, corrupted 1/1 (100.0%) client | 0.303 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 }) client | 0.303 INFO Lost crypto frame space=in offset=0 length=1208 client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Lost: EcnEct0 client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 }) client | 0.303 INFO Lost crypto frame space=in offset=1208 length=331 client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Lost: EcnEct0 client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.303 DEBUG Building Initial dcid Some(CID [13]: bfd3c0e1630ceb4b423ab70e5c) scid Some(CID [0]: ) client | 0.303 DEBUG CRYPTO for in offset=0, len=1208 client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=2 type=Initial pri-path:bfd3c0e1630ceb4b423ab70e5c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 0.303 DEBUG packet_sent this=0x556039619be0, pn=2, ps=1252 client | 0.303 DEBUG ECN probing: sent 3 probes client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.303 DEBUG Building Initial dcid Some(CID [13]: bfd3c0e1630ceb4b423ab70e5c) scid Some(CID [0]: ) client | 0.303 DEBUG CRYPTO for in offset=1208, len=331 client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=3 type=Initial pri-path:bfd3c0e1630ceb4b423ab70e5c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 376 client | TX -> Crypto { offset: 1208, len: 331 } client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pad Initial from 376 to PLPMTU 1252 client | 0.303 DEBUG packet_sent this=0x556039619be0, pn=3, ps=1252 client | 0.303 DEBUG ECN probing: sent 4 probes client | 0.303 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.303 DEBUG Building Initial dcid Some(CID [13]: bfd3c0e1630ceb4b423ab70e5c) 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 bfd3c0e1630ceb4b423ab70e5c] delay duration 599.782764ms client | 0.304 DEBUG Setting timeout of 599.782764ms sim | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 1/2 (50.0%) client | 0.316 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=1 type=Initial pri-path:bfd3c0e1630ceb4b423ab70e5c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 459 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Padding { len: 325 } client | 0.316 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Rx ACK space=in, ranges=[0..=1] client | 0.316 DEBUG [LossRecovery] ACK for Initial - largest_acked=1 client | 0.316 DEBUG on_packets_acked this=0x556039619be0, limited=1, bytes_in_flight=2504, cwnd=12520, state=SlowStart, new_acked=2504 client | 0.316 DEBUG Acked crypto frame space=in offset=1208 length=331 client | 0.316 DEBUG Acked crypto frame space=in offset=0 length=1208 client | 0.316 DEBUG Read Ok(90) bytes client | 0.317 DEBUG Write secret available for Handshake: SymKey [32]: 156b81e3b95a84bbaf4340437b5a030a093397cd4f18a53669e76e78784c97ef client | 0.317 DEBUG Read secret available for Handshake: SymKey [32]: 954313dd03c4a58200c9be7877399ad8680422b4bcc7ce637ce60ab20cad4b4a client | 0.317 DEBUG [Agent 0x5560396062d0] state -> InProgress client | 0.317 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 0.317 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 0.317 DEBUG [Crypto] Handshake keys installed client | 0.317 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 608, tv_nsec: 596183856 } client | 0.317 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Changing to use Server CID=a596100160cff15f client | 0.317 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] State change from WaitInitial -> Handshaking client | 0.317 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=0 type=Handshake pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 741 client | -> RX Crypto { offset: 0, len: 703 } client | 0.317 DEBUG Read Ok(703) bytes client | 0.318 DEBUG [Agent 0x5560396062d0] state -> AuthenticationPending client | 0.318 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 608, tv_nsec: 596183856 } client | 0.318 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.318 DEBUG Building Initial dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.318 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=4 type=Initial pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 41 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 232, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.318 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.318 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=0 type=Handshake pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 81 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 232, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.318 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Drop packet number space in client | 0.318 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 0.318 WARN [LossRecovery] ignoring in-4 from dropped space client | 0.318 DEBUG ECN probing: sent 5 probes client | 0.318 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.318 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.318 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.318 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 942.981603ms client | 0.318 DEBUG Setting timeout of 942.981603ms client | 0.318 DEBUG StateChange(Handshaking) client | 0.318 WARN Cannot create stream ConnectionState client | 0.318 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Authenticated Ok client | 0.319 DEBUG Write secret available for ApplicationData: SymKey [32]: 71bbff652ee506546bed31e772368cc1cbe41394873f0c76d46acfd7585ae13c client | 0.319 DEBUG Read secret available for ApplicationData: SymKey [32]: 4145062c1ed2adb7a3bda35ab7b2e21619420f309f6864538a92bc453363afe8 client | 0.319 DEBUG [Agent 0x5560396062d0] SSL_AuthCertificateComplete: Ok(()) client | 0.319 DEBUG [Agent 0x5560396062d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.319 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] TLS connection complete client | 0.319 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 0.319 DEBUG [Crypto] Application write key installed client | 0.319 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 sim | Corrupting 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 42 0x7 -> 0x2b, corrupted 1/3 (33.3%) client | 0.319 DEBUG [Crypto] application read keys installed client | 0.319 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] State change from Handshaking -> Connected client | 0.319 INFO [Client bfd3c0e1630ceb4b423ab70e5c] Connection established client | 0.319 DEBUG stream BiDi creatable client | 0.319 INFO Created stream 0 for https://server4/ettjxoyggo client | 0.319 INFO Saving https://server4/ettjxoyggo to "/downloads/ettjxoyggo" client | 0.320 DEBUG StateChange(Connected) client | 0.320 DEBUG stream 0 writable client | 0.320 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.320 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.320 DEBUG CRYPTO for hs offset=0, len=36 client | 0.320 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=1 type=Handshake pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 0.320 DEBUG packet_sent this=0x556039619be0, pn=1, ps=73 client | 0.320 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.320 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=0 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 119 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.320 DEBUG packet_sent this=0x556039619be0, pn=0, ps=46 client | 0.320 DEBUG ECN probing: sent 6 probes client | 0.320 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.320 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.320 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.320 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.320 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 943.089445ms sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 1/4 (25.0%) client | 0.320 DEBUG Setting timeout of 943.089445ms sim | Forwarding 81 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 1/5 (20.0%) sim | Corrupting 119 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 22 0x73 -> 0x5e, corrupted 2/6 (33.3%) sim | Forwarding 1201 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 1/3 (33.3%) client | 0.344 WARN [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 0.344 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.344 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.344 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.344 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.344 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 919.176564ms client | 0.344 DEBUG Setting timeout of 919.176564ms client | 0.344 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.344 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.344 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.344 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.344 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 919.087888ms client | 0.344 DEBUG Setting timeout of 919.087888ms sim | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 1/4 (25.0%) client | 0.519 WARN [Client ...] Dropped received packet: Decryption failure; Total: 2 client | 0.519 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.519 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.519 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.519 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.519 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 743.915147ms client | 0.519 DEBUG Setting timeout of 743.915147ms client | 0.519 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.519 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.519 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.519 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.519 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 743.864122ms client | 0.519 DEBUG Setting timeout of 743.864122ms sim | Corrupting 741 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 16 0xd4 -> 0x3c, corrupted 2/5 (40.0%) sim | Forwarding 741 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 2/6 (33.3%) client | 0.716 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=2 type=Handshake pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 741 client | -> RX Crypto { offset: 0, len: 703 } client | 0.716 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 608, tv_nsec: 996189692 } client | 0.716 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.716 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.716 DEBUG CRYPTO for hs offset=0, len=36 client | 0.716 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=2 type=Handshake pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 80 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 8, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 0.716 DEBUG packet_sent this=0x556039619be0, pn=2, ps=80 client | 0.716 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.716 DEBUG ECN probing: sent 7 probes client | 0.716 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.716 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.716 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.716 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.716 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 943.309577ms client | 0.716 DEBUG Setting timeout of 943.309577ms client | 0.716 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.716 DEBUG Building Handshake dcid Some(CID [8]: a596100160cff15f) scid Some(CID [0]: ) client | 0.716 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.716 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.716 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 943.275824ms client | 0.716 DEBUG Setting timeout of 943.275824ms sim | Forwarding 80 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 2/7 (28.6%) sim | Corrupting 204 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 4 0x3c -> 0x15, corrupted 3/7 (42.9%) sim | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 3/8 (37.5%) client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=1 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 204 client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [8, 48, 229, 5, 164, 140, 243, 229], stateless_reset_token: [103, 214, 252, 215, 222, 119, 88, 67, 133, 219, 83, 24, 84, 151, 187, 193] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [205, 116, 218, 135, 61, 106, 50, 227], stateless_reset_token: [32, 79, 165, 199, 86, 240, 1, 197, 31, 227, 55, 36, 221, 72, 152, 72] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [226, 197, 171, 122, 17, 95, 203, 110], stateless_reset_token: [46, 248, 22, 12, 51, 63, 76, 33, 63, 222, 130, 28, 118, 208, 94, 91] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [14, 0, 172, 122, 222, 23, 112, 232], stateless_reset_token: [143, 79, 60, 154, 10, 174, 106, 225, 140, 244, 99, 240, 64, 180, 31, 133] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [221, 238, 4, 24, 149, 112, 235, 115], stateless_reset_token: [85, 169, 202, 229, 182, 17, 149, 51, 171, 209, 112, 152, 68, 78, 49, 24] } client | -> RX Crypto { offset: 0, len: 42 } client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] State change from Connected -> Confirmed client | 0.889 DEBUG PMTUD started with probe size 1380 client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Drop packet number space hs client | 0.889 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] No preferred address to migrate to client | 0.889 DEBUG Read Ok(42) bytes client | 0.889 DEBUG [0x5560396062d0] Got resumption token [712]: 02000633fcb95785..742e75bd3e42dbe0 client | 0.889 DEBUG [Agent 0x5560396062d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.889 DEBUG setting max_stream_data to 1000000 client | 0.889 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 609, tv_nsec: 168604533 } client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.889 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.889 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=1 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 39, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> Ping client | TX -> Padding { len: 1320 } client | 0.889 DEBUG packet_sent this=0x556039619be0, pn=1, ps=1352 client | 0.889 DEBUG ECN probing: sent 8 probes client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.889 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.889 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 968.280853ms client | 0.889 DEBUG Setting timeout of 968.280853ms client | 0.889 WARN Unhandled event StateChange(Confirmed) client | 0.889 DEBUG stream BiDi creatable client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.889 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.889 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.889 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 968.249584ms client | 0.889 DEBUG Setting timeout of 968.249584ms sim | Forwarding 1352 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 2/8 (25.0%) sim | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 3/9 (33.3%) client | 0.925 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=2 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 204 client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [8, 48, 229, 5, 164, 140, 243, 229], stateless_reset_token: [103, 214, 252, 215, 222, 119, 88, 67, 133, 219, 83, 24, 84, 151, 187, 193] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [205, 116, 218, 135, 61, 106, 50, 227], stateless_reset_token: [32, 79, 165, 199, 86, 240, 1, 197, 31, 227, 55, 36, 221, 72, 152, 72] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [226, 197, 171, 122, 17, 95, 203, 110], stateless_reset_token: [46, 248, 22, 12, 51, 63, 76, 33, 63, 222, 130, 28, 118, 208, 94, 91] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [14, 0, 172, 122, 222, 23, 112, 232], stateless_reset_token: [143, 79, 60, 154, 10, 174, 106, 225, 140, 244, 99, 240, 64, 180, 31, 133] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [221, 238, 4, 24, 149, 112, 235, 115], stateless_reset_token: [85, 169, 202, 229, 182, 17, 149, 51, 171, 209, 112, 152, 68, 78, 49, 24] } client | -> RX Crypto { offset: 0, len: 42 } client | 0.925 DEBUG PMTUD started with probe size 1420 client | 0.925 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] No preferred address to migrate to client | 0.925 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 609, tv_nsec: 224783963 } client | 0.925 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.925 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.925 DEBUG Sending PMTUD probe of size 1420, count 1 client | 0.925 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=2 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1392 client | TX -> Ping client | TX -> Padding { len: 1365 } client | 0.925 DEBUG packet_sent this=0x556039619be0, pn=2, ps=1392 client | 0.925 DEBUG ECN probing: sent 9 probes client | 0.925 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.925 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.925 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.925 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 19.68991ms client | 0.925 DEBUG Setting timeout of 19.68991ms client | 0.925 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.925 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.925 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.925 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 19.661016ms client | 0.925 DEBUG Setting timeout of 19.661016ms sim | Corrupting 1392 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 2 0x96 -> 0x3, corrupted 3/9 (33.3%) client | 0.946 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.946 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.946 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=3 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 32 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 2679, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.946 DEBUG ECN probing: sent 10 probes client | 0.946 DEBUG ECN probing concluded with 10 probes sent sim | Forwarding 24 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 3/10 (30.0%) client | 0.946 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.946 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.946 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.946 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 947.037893ms client | 0.946 DEBUG Setting timeout of 947.037893ms client | 0.946 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=3 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 24 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 2375, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.946 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Rx ACK space=ap, ranges=[1..=1] client | 0.946 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 client | 0.946 DEBUG packet_lost this=0x556039619be0, pn=0, ps=46 client | 0.946 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2744/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764 client | 0.946 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2744/8764 ssthresh 8764] state -> RecoveryStart client | 0.946 DEBUG on_packets_lost this=0x556039619be0, bytes_in_flight=2744, cwnd=8764, state=RecoveryStart client | 0.946 WARN ECN validation failed, no ECN counts in ACK frame client | 0.946 DEBUG on_packets_acked this=0x556039619be0, limited=1, bytes_in_flight=1392, cwnd=8764, state=RecoveryStart, new_acked=0 client | 0.946 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Lost: Stream(Stream(SendStreamRecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true })) client | 0.947 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.947 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.947 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=4 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 46 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.947 DEBUG packet_sent this=0x556039619be0, pn=4, ps=46 client | 0.947 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.947 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.947 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.947 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 1.035877656s client | 0.947 DEBUG Setting timeout of 1.035877656s client | 0.947 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.947 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.947 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.947 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 1.035850725s client | 0.947 DEBUG Setting timeout of 1.035850725s sim | Forwarding 32 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 3/10 (30.0%) sim | Forwarding 46 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 3/11 (27.3%) sim | Forwarding 1058 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 3/11 (27.3%) client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=4 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1058 client | -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true } client | -> RX Ack { largest_acknowledged: 4, ack_delay: 125, first_ack_range: 1, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None } client | -> RX MaxStreams { stream_type: BiDi, maximum_streams: 101 } client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] Rx ACK space=ap, ranges=[3..=4, 1..=1] client | 0.986 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 client | 0.986 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1392/8764 ssthresh 8764] state -> CongestionAvoidance client | 0.986 DEBUG on_packets_acked this=0x556039619be0, limited=1, bytes_in_flight=1392, cwnd=8764, state=CongestionAvoidance, new_acked=46 client | 0.986 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 609, tv_nsec: 286147045 } client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.986 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.986 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 19.849037ms client | 0.986 DEBUG Setting timeout of 19.849037ms client | 0.986 DEBUG stream 0 complete client | 0.986 DEBUG stream BiDi creatable client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.986 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.986 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] delay duration 19.609257ms client | 0.986 DEBUG Setting timeout of 19.609257ms client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 612, tv_nsec: 465398056 } } client | 0.986 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 612, tv_nsec: 465398056 } }) client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.986 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 0.986 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 609, tv_nsec: 266567704 } client | 0.986 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=5 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 52, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.987 DEBUG Setting timeout of 3.1987491s sim | Corrupting 42 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 25 0xdb -> 0x8b, corrupted 4/12 (33.3%) sim | Corrupting 1050 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 19 0x8e -> 0x73, corrupted 4/12 (33.3%) sim | Forwarding 1050 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 4/13 (30.8%) client | 1.250 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.250 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 1.250 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 609, tv_nsec: 530411726 } client | 1.250 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=6 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 45 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 33033, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 1.250 DEBUG Setting timeout of 2.934853993s client | 1.250 DEBUG Setting timeout of 2.934840117s sim | Forwarding 1050 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 4/14 (28.6%) client | 1.251 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.251 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 1.251 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 609, tv_nsec: 531178257 } client | 1.251 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=7 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 45 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 33128, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 1.251 DEBUG Setting timeout of 2.934146582s client | 1.251 DEBUG Setting timeout of 2.9341349s sim | Forwarding 45 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 4/13 (30.8%) sim | Forwarding 45 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 4/14 (28.6%) sim | Forwarding 27 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 4/15 (26.7%) client | 1.289 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.289 DEBUG Building Short dcid Some(CID [8]: a596100160cff15f) client | 1.289 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 609, tv_nsec: 568716534 } client | 1.289 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] pn=8 type=Short pri-path:a596100160cff15f 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 45 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 37821, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 1.289 DEBUG Setting timeout of 2.896531902s client | 1.289 DEBUG Setting timeout of 2.89651994s sim | Corrupting 45 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 30 0xc2 -> 0xe1, corrupted 5/15 (33.3%) server | QLog: done with bfd3c0e1630ceb4b423ab70e5c.qlog client | 4.187 DEBUG [Client bfd3c0e1630ceb4b423ab70e5c] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 612, tv_nsec: 465398056 } } -> Closed(Application(0)) client | 4.187 INFO Closing timer expired client | 4.187 DEBUG Output::None client | 4.187 WARN Unhandled event StateChange(Closed(Application(0))) client | 4.187 DEBUG Timer fired while closed client | 4.187 DEBUG Output::None client | 4.187 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=b8962eb585627a8a06a64ec9b0 client | 4.187 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 4.187 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 client | 4.187 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 4.187 DEBUG [unv-path 0.0.0.0:53438->193.167.100.100:443] Make permanent client | 4.187 DEBUG [unv-path:b8962eb585627a8a06a64ec9b0 0.0.0.0:53438->193.167.100.100:443] set as primary path client | 4.187 DEBUG [pri-unv-path:b8962eb585627a8a06a64ec9b0 0.0.0.0:53438->193.167.100.100:443] Path validated Instant { tv_sec: 612, tv_nsec: 467241433 } client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] client_start client | 4.188 DEBUG Writing transport parameters, msg=1 client | 4.188 DEBUG [Agent 0x55603965a770] state -> InProgress client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] State change from Init -> WaitInitial client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.188 DEBUG Building Initial dcid Some(CID [13]: b8962eb585627a8a06a64ec9b0) scid Some(CID [0]: ) client | 4.188 DEBUG CRYPTO for in offset=0, len=1208 client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=0 type=Initial pri-path:b8962eb585627a8a06a64ec9b0 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 4.188 DEBUG packet_sent this=0x556039619be0, pn=0, ps=1252 client | 4.188 DEBUG ECN probing: sent 1 probes client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.188 DEBUG Building Initial dcid Some(CID [13]: b8962eb585627a8a06a64ec9b0) scid Some(CID [0]: ) client | 4.188 DEBUG CRYPTO for in offset=1208, len=331 client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=1 type=Initial pri-path:b8962eb585627a8a06a64ec9b0 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 376 client | TX -> Crypto { offset: 1208, len: 331 } client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] pad Initial from 376 to PLPMTU 1252 client | 4.188 DEBUG packet_sent this=0x556039619be0, pn=1, ps=1252 client | 4.188 DEBUG ECN probing: sent 2 probes client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 4.188 DEBUG Building Initial dcid Some(CID [13]: b8962eb585627a8a06a64ec9b0) scid Some(CID [0]: ) client | 4.188 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 3.924123ms client | 4.188 DEBUG Setting timeout of 3.924123ms client | 4.188 DEBUG StateChange(WaitInitial) client | 4.188 WARN Cannot create stream ConnectionState client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 4.188 DEBUG Building Initial dcid Some(CID [13]: b8962eb585627a8a06a64ec9b0) scid Some(CID [0]: ) client | 4.188 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 4.188 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 3.901762ms client | 4.188 DEBUG Setting timeout of 3.901762ms client | 4.193 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.193 DEBUG Building Initial dcid Some(CID [13]: b8962eb585627a8a06a64ec9b0) scid Some(CID [0]: ) client | 4.193 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.193 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 294.829474ms client | 4.193 DEBUG Setting timeout of 294.829474ms sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 5/16 (31.2%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 5/17 (29.4%) sim | Corrupting 1201 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 20 0x76 -> 0x3, corrupted 5/16 (31.2%) sim | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 5/17 (29.4%) client | 4.235 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=1 type=Initial pri-path:b8962eb585627a8a06a64ec9b0 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 459 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Padding { len: 325 } client | 4.235 DEBUG [Client b8962eb585627a8a06a64ec9b0] Rx ACK space=in, ranges=[0..=1] client | 4.235 DEBUG [LossRecovery] ACK for Initial - largest_acked=1 client | 4.235 DEBUG on_packets_acked this=0x556039619be0, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=2504 client | 4.235 DEBUG Acked crypto frame space=in offset=1208 length=331 client | 4.235 DEBUG Acked crypto frame space=in offset=0 length=1208 client | 4.235 DEBUG Read Ok(90) bytes client | 4.235 DEBUG Write secret available for Handshake: SymKey [32]: 2a26ef6db3ba8a57473dfeff3711bf15d58e4da24504f8fde9d3e0c6a8fcf24b client | 4.235 DEBUG Read secret available for Handshake: SymKey [32]: 974beb09e7ca883ce05b123a77bac9c330961bc0b684b6d09bf3d91f7bcbb5c3 client | 4.235 DEBUG [Agent 0x55603965a770] state -> InProgress client | 4.235 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 4.235 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 4.235 DEBUG [Crypto] Handshake keys installed client | 4.235 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 612, tv_nsec: 514735925 } client | 4.235 DEBUG [Client b8962eb585627a8a06a64ec9b0] Changing to use Server CID=9afe491538ae2e59 client | 4.235 DEBUG [Client b8962eb585627a8a06a64ec9b0] State change from WaitInitial -> Handshaking client | 4.235 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=0 type=Handshake pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 741 client | -> RX Crypto { offset: 0, len: 703 } client | 4.235 DEBUG Read Ok(703) bytes client | 4.236 DEBUG [Agent 0x55603965a770] state -> AuthenticationPending client | 4.236 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 612, tv_nsec: 514735925 } client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.236 DEBUG Building Initial dcid Some(CID [8]: 9afe491538ae2e59) scid Some(CID [0]: ) client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=2 type=Initial pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 41 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 148, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 4.236 DEBUG Building Handshake dcid Some(CID [8]: 9afe491538ae2e59) scid Some(CID [0]: ) client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=0 type=Handshake pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 81 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 148, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] Drop packet number space in client | 4.236 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 4.236 WARN [LossRecovery] ignoring in-2 from dropped space client | 4.236 DEBUG ECN probing: sent 3 probes client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.236 DEBUG Building Handshake dcid Some(CID [8]: 9afe491538ae2e59) scid Some(CID [0]: ) client | 4.236 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 139.302064ms client | 4.236 DEBUG Setting timeout of 139.302064ms client | 4.236 DEBUG StateChange(Handshaking) client | 4.236 WARN Cannot create stream ConnectionState client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] Authenticated Ok client | 4.236 DEBUG Write secret available for ApplicationData: SymKey [32]: 3dd2d66e0892241e3808fefcda9ab8a42ac490b189ed8702ee7f818329106669 client | 4.236 DEBUG Read secret available for ApplicationData: SymKey [32]: 9bc5df263194d3ab192db16680197a3d9da0dc8f0555fd9c1fda7f66edc320e7 client | 4.236 DEBUG [Agent 0x55603965a770] SSL_AuthCertificateComplete: Ok(()) client | 4.236 DEBUG [Agent 0x55603965a770] 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 | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] TLS connection complete client | 4.236 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 4.236 DEBUG [Crypto] Application write key installed client | 4.236 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 4.236 DEBUG [Crypto] application read keys installed client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] State change from Handshaking -> Connected client | 4.236 INFO [Client b8962eb585627a8a06a64ec9b0] Connection established client | 4.236 DEBUG stream BiDi creatable client | 4.236 INFO Created stream 0 for https://server4/bqklknwytu client | 4.236 INFO Saving https://server4/bqklknwytu to "/downloads/bqklknwytu" client | 4.236 DEBUG StateChange(Connected) client | 4.236 DEBUG stream 0 writable client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.236 DEBUG Building Handshake dcid Some(CID [8]: 9afe491538ae2e59) scid Some(CID [0]: ) client | 4.236 DEBUG CRYPTO for hs offset=0, len=36 client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=1 type=Handshake pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 4.236 DEBUG packet_sent this=0x556039619be0, pn=1, ps=73 client | 4.236 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.236 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=0 type=Short pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 119 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 4.236 DEBUG packet_sent this=0x556039619be0, pn=0, ps=46 client | 4.236 DEBUG ECN probing: sent 4 probes client | 4.237 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.237 DEBUG Building Handshake dcid Some(CID [8]: 9afe491538ae2e59) scid Some(CID [0]: ) client | 4.237 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.237 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.237 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 139.297324ms client | 4.237 DEBUG Setting timeout of 139.297324ms sim | Forwarding 81 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 5/18 (27.8%) sim | Forwarding 119 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 5/19 (26.3%) sim | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 5/18 (27.8%) client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=0 type=Short pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 204 client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [28, 171, 130, 114, 101, 36, 78, 132], stateless_reset_token: [136, 86, 67, 26, 108, 49, 166, 203, 191, 128, 35, 105, 166, 219, 174, 54] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [119, 5, 140, 209, 142, 229, 24, 63], stateless_reset_token: [74, 214, 165, 252, 11, 11, 81, 153, 51, 252, 91, 88, 27, 203, 124, 82] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [96, 51, 149, 208, 186, 227, 95, 83], stateless_reset_token: [60, 117, 177, 54, 184, 136, 117, 112, 182, 117, 4, 129, 152, 254, 158, 174] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [77, 69, 98, 215, 255, 228, 64, 235], stateless_reset_token: [32, 255, 150, 145, 170, 123, 146, 167, 135, 154, 99, 20, 75, 244, 68, 239] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [216, 246, 46, 74, 82, 16, 146, 230], stateless_reset_token: [192, 102, 225, 144, 65, 240, 219, 145, 242, 87, 152, 63, 206, 10, 187, 163] } client | -> RX Crypto { offset: 0, len: 42 } client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] State change from Connected -> Confirmed client | 4.270 DEBUG PMTUD started with probe size 1380 client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] Drop packet number space hs client | 4.270 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] No preferred address to migrate to client | 4.270 DEBUG Read Ok(42) bytes client | 4.270 DEBUG [0x55603965a770] Got resumption token [712]: 02000633fcb98b1d..800678829e5e6bb7 client | 4.270 DEBUG [Agent 0x55603965a770] 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 | 4.270 DEBUG setting max_stream_data to 1000000 client | 4.270 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 612, tv_nsec: 569816341 } client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.270 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.270 DEBUG Sending PMTUD probe of size 1380, count 1 client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=1 type=Short pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352 client | TX -> Ping client | TX -> Padding { len: 1325 } client | 4.270 DEBUG packet_sent this=0x556039619be0, pn=1, ps=1352 client | 4.270 DEBUG ECN probing: sent 5 probes client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.270 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.270 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 19.560927ms client | 4.270 DEBUG Setting timeout of 19.560927ms client | 4.270 WARN Unhandled event StateChange(Confirmed) client | 4.270 DEBUG stream BiDi creatable client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.270 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.270 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.270 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 19.529257ms client | 4.270 DEBUG Setting timeout of 19.529257ms sim | Forwarding 1055 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 5/19 (26.3%) client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=1 type=Short pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1055 client | -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX MaxStreams { stream_type: BiDi, maximum_streams: 101 } client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] Rx ACK space=ap, ranges=[0..=0] client | 4.272 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 client | 4.272 DEBUG on_packets_acked this=0x556039619be0, limited=1, bytes_in_flight=1352, cwnd=12520, state=SlowStart, new_acked=46 client | 4.272 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 612, tv_nsec: 551803460 } client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.272 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=2 type=Short pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 6, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 4.272 DEBUG ECN probing: sent 6 probes client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.272 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.272 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 149.213613ms client | 4.272 DEBUG Setting timeout of 149.213613ms client | 4.272 DEBUG stream 0 complete client | 4.272 DEBUG stream BiDi creatable client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.272 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.272 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] delay duration 149.136419ms client | 4.272 DEBUG Setting timeout of 149.136419ms client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 613, tv_nsec: 5122209 } } client | 4.272 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 613, tv_nsec: 5122209 } }) client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.272 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.272 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 612, tv_nsec: 552071491 } client | 4.272 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=3 type=Short pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 42 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 33, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.272 DEBUG ECN probing: sent 7 probes client | 4.272 DEBUG Setting timeout of 452.932206ms sim | Forwarding 1352 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 5/20 (25.0%) sim | Forwarding 31 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 5/21 (23.8%) sim | Forwarding 42 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 5/22 (22.7%) sim | Forwarding 28 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 5/20 (25.0%) client | 4.304 DEBUG [Client b8962eb585627a8a06a64ec9b0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.304 DEBUG Building Short dcid Some(CID [8]: 9afe491538ae2e59) client | 4.304 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 612, tv_nsec: 584127043 } client | 4.304 DEBUG [Client b8962eb585627a8a06a64ec9b0] pn=4 type=Short pri-path:9afe491538ae2e59 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 4040, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.304 DEBUG ECN probing: sent 8 probes client | 4.304 DEBUG Setting timeout of 420.855857ms client | 4.304 DEBUG Setting timeout of 420.84117ms sim | Forwarding 43 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 5/23 (21.7%) server | QLog: done with b8962eb585627a8a06a64ec9b0.qlog client | 4.726 DEBUG [Client b8962eb585627a8a06a64ec9b0] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 613, tv_nsec: 5122209 } } -> Closed(Application(0)) client | 4.726 INFO Closing timer expired client | 4.726 DEBUG Output::None client | 4.726 WARN Unhandled event StateChange(Closed(Application(0))) client | 4.726 DEBUG Timer fired while closed client | 4.726 DEBUG Output::None client | 4.726 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=963a57627958fad3 client | 4.726 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 4.726 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 client | 4.726 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 4.726 DEBUG [unv-path 0.0.0.0:53438->193.167.100.100:443] Make permanent client | 4.726 DEBUG [unv-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443] set as primary path client | 4.726 DEBUG [pri-unv-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443] Path validated Instant { tv_sec: 613, tv_nsec: 6051674 } client | 4.726 DEBUG [Client 963a57627958fad3] client_start client | 4.727 DEBUG Writing transport parameters, msg=1 client | 4.727 DEBUG [Agent 0x556039657420] state -> InProgress client | 4.727 DEBUG [Client 963a57627958fad3] State change from Init -> WaitInitial client | 4.727 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.727 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 4.727 DEBUG CRYPTO for in offset=0, len=1213 client | 4.727 DEBUG [Client 963a57627958fad3] pn=0 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 4.727 DEBUG packet_sent this=0x556039619be0, pn=0, ps=1252 client | 4.727 DEBUG ECN probing: sent 1 probes client | 4.727 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.727 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 4.727 DEBUG CRYPTO for in offset=1213, len=326 client | 4.727 DEBUG [Client 963a57627958fad3] pn=1 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 4.727 DEBUG [Client 963a57627958fad3] pad Initial from 366 to PLPMTU 1252 client | 4.727 DEBUG packet_sent this=0x556039619be0, pn=1, ps=1252 client | 4.727 DEBUG ECN probing: sent 2 probes client | 4.727 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 4.727 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 4.727 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 4.727 DEBUG [Client 963a57627958fad3] delay duration 4.102715ms client | 4.727 DEBUG Setting timeout of 4.102715ms client | 4.727 DEBUG StateChange(WaitInitial) client | 4.727 WARN Cannot create stream ConnectionState client | 4.727 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 4.727 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 4.727 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 4.727 DEBUG [Client 963a57627958fad3] delay duration 4.079441ms client | 4.727 DEBUG Setting timeout of 4.079441ms client | 4.732 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.732 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 4.732 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.732 DEBUG [Client 963a57627958fad3] delay duration 294.866129ms client | 4.732 DEBUG Setting timeout of 294.866129ms sim | Corrupting 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 12 0xfa -> 0xfd, corrupted 6/24 (25.0%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 6/25 (24.0%) client | 5.028 DEBUG [LossRecovery] PTO timer fired for Initial client | 5.028 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 5.028 INFO Lost crypto frame space=in offset=0 length=1213 client | 5.028 DEBUG [Client 963a57627958fad3] Lost: EcnEct0 client | 5.028 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 5.028 INFO Lost crypto frame space=in offset=1213 length=326 client | 5.028 DEBUG [Client 963a57627958fad3] Lost: EcnEct0 client | 5.028 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 5.028 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 5.028 DEBUG CRYPTO for in offset=0, len=1213 client | 5.028 DEBUG [Client 963a57627958fad3] pn=2 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 5.028 DEBUG packet_sent this=0x556039619be0, pn=2, ps=1252 client | 5.028 DEBUG ECN probing: sent 3 probes client | 5.029 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 5.029 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 5.029 DEBUG CRYPTO for in offset=1213, len=326 client | 5.029 DEBUG [Client 963a57627958fad3] pn=3 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 5.029 DEBUG [Client 963a57627958fad3] pad Initial from 366 to PLPMTU 1252 client | 5.029 DEBUG packet_sent this=0x556039619be0, pn=3, ps=1252 client | 5.029 DEBUG ECN probing: sent 4 probes client | 5.029 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 5.029 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 5.029 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 5.029 DEBUG [Client 963a57627958fad3] delay duration 599.924359ms client | 5.029 DEBUG Setting timeout of 599.924359ms sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 6/26 (23.1%) sim | Corrupting 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 13 0xd3 -> 0x6, corrupted 7/27 (25.9%) client | 5.629 DEBUG [LossRecovery] PTO timer fired for Initial client | 5.629 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 5.629 INFO Lost crypto frame space=in offset=0 length=1213 client | 5.629 DEBUG [Client 963a57627958fad3] Lost: EcnEct0 client | 5.629 DEBUG ECN validation failed, all 3 initial marked packets were lost client | 5.629 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 5.629 INFO Lost crypto frame space=in offset=1213 length=326 client | 5.629 DEBUG [Client 963a57627958fad3] Lost: EcnEct0 client | 5.629 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 5.629 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 5.629 DEBUG CRYPTO for in offset=0, len=1213 client | 5.629 DEBUG [Client 963a57627958fad3] pn=4 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 5.630 DEBUG packet_sent this=0x556039619be0, pn=4, ps=1252 client | 5.630 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 5.630 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 5.630 DEBUG CRYPTO for in offset=1213, len=326 client | 5.630 DEBUG [Client 963a57627958fad3] pn=5 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 5.630 DEBUG [Client 963a57627958fad3] pad Initial from 366 to PLPMTU 1252 client | 5.630 DEBUG packet_sent this=0x556039619be0, pn=5, ps=1252 client | 5.630 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 5.630 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 5.630 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 5.630 DEBUG [Client 963a57627958fad3] delay duration 1.199920872s client | 5.630 DEBUG Setting timeout of 1.199920872s sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 7/28 (25.0%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 7/29 (24.1%) client | 6.831 DEBUG [LossRecovery] PTO timer fired for Initial client | 6.831 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 6.831 INFO Lost crypto frame space=in offset=0 length=1213 client | 6.831 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 6.831 INFO Lost crypto frame space=in offset=1213 length=326 client | 6.831 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 6.831 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 6.831 DEBUG CRYPTO for in offset=0, len=1213 client | 6.831 DEBUG [Client 963a57627958fad3] pn=6 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 6.831 DEBUG packet_sent this=0x556039619be0, pn=6, ps=1252 client | 6.831 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.831 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 6.831 DEBUG CRYPTO for in offset=1213, len=326 client | 6.831 DEBUG [Client 963a57627958fad3] pn=7 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 6.831 DEBUG [Client 963a57627958fad3] pad Initial from 366 to PLPMTU 1252 client | 6.831 DEBUG packet_sent this=0x556039619be0, pn=7, ps=1252 client | 6.831 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.831 DEBUG Building Initial dcid Some(CID [8]: 963a57627958fad3) scid Some(CID [0]: ) client | 6.831 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.831 DEBUG [Client 963a57627958fad3] delay duration 2.39991983s client | 6.831 DEBUG Setting timeout of 2.39991983s sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 7/30 (23.3%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 7/31 (22.6%) sim | Corrupting 1201 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 32 0x6c -> 0x39, corrupted 6/21 (28.6%) sim | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 6/22 (27.3%) client | 6.873 DEBUG [Client 963a57627958fad3] pn=1 type=Initial pri-path:963a57627958fad3 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 464 client | -> RX Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Padding { len: 330 } client | 6.873 DEBUG [Client 963a57627958fad3] Rx ACK space=in, ranges=[6..=7] client | 6.874 DEBUG [LossRecovery] ACK for Initial - largest_acked=7 client | 6.874 DEBUG packet_lost this=0x556039619be0, pn=0, ps=1252 client | 6.874 DEBUG packet_lost this=0x556039619be0, pn=1, ps=1252 client | 6.874 DEBUG packet_lost this=0x556039619be0, pn=2, ps=1252 client | 6.874 DEBUG packet_lost this=0x556039619be0, pn=3, ps=1252 client | 6.874 DEBUG packet_lost this=0x556039619be0, pn=4, ps=1252 client | 6.874 DEBUG packet_lost this=0x556039619be0, pn=5, ps=1252 client | 6.874 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2504/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764 client | 6.874 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2504/8764 ssthresh 8764] state -> RecoveryStart client | 6.874 DEBUG on_packets_lost this=0x556039619be0, bytes_in_flight=2504, cwnd=8764, state=RecoveryStart client | 6.874 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 615, tv_nsec: 153510120 })] CongCtrl 0/8764 ssthresh 8764] on_packets_acked this=0x556039619be0, limited=0, bytes_in_flight=0, cwnd=8764, state=RecoveryStart, new_acked=0 client | 6.874 DEBUG Acked crypto frame space=in offset=1213 length=326 client | 6.874 DEBUG Acked crypto frame space=in offset=0 length=1213 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 6.874 INFO Lost crypto frame space=in offset=0 length=1213 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: EcnEct0 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 6.874 INFO Lost crypto frame space=in offset=1213 length=326 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: EcnEct0 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 6.874 INFO Lost crypto frame space=in offset=0 length=1213 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: EcnEct0 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 6.874 INFO Lost crypto frame space=in offset=1213 length=326 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: EcnEct0 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 6.874 INFO Lost crypto frame space=in offset=0 length=1213 client | 6.874 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 6.874 INFO Lost crypto frame space=in offset=1213 length=326 client | 6.874 DEBUG Read Ok(90) bytes client | 6.874 DEBUG Write secret available for Handshake: SymKey [32]: 0b370f13e7083ae4f268eac1fd3fbe3e4a9e746e9c15713427df1c4553e0c92f client | 6.874 DEBUG Read secret available for Handshake: SymKey [32]: e5fe44b51083ef4d5b53a1c0f769d46504321ee92eac8b634bb7395973838c6b client | 6.874 DEBUG [Agent 0x556039657420] state -> InProgress client | 6.874 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 6.874 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 6.874 DEBUG [Crypto] Handshake keys installed client | 6.874 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 615, tv_nsec: 153510120 } client | 6.874 DEBUG [Client 963a57627958fad3] Changing to use Server CID=ad27c986f6f9eaf1 client | 6.874 DEBUG [Client 963a57627958fad3] State change from WaitInitial -> Handshaking client | 6.874 DEBUG [Client 963a57627958fad3] pn=0 type=Handshake pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 736 client | -> RX Crypto { offset: 0, len: 698 } client | 6.874 DEBUG Read Ok(698) bytes client | 6.875 DEBUG [Agent 0x556039657420] state -> AuthenticationPending client | 6.875 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 615, tv_nsec: 153510120 } client | 6.875 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.875 DEBUG Building Initial dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 6.875 DEBUG [Client 963a57627958fad3] pn=8 type=Initial pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 41 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 166, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 6.875 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 6.875 DEBUG [Client 963a57627958fad3] pn=0 type=Handshake pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 81 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 166, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 6.875 DEBUG [Client 963a57627958fad3] Drop packet number space in client | 6.875 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 6.875 WARN [LossRecovery] ignoring in-8 from dropped space client | 6.875 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.875 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 6.875 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.875 DEBUG [Client 963a57627958fad3] delay duration 126.261877ms client | 6.875 DEBUG Setting timeout of 126.261877ms client | 6.875 DEBUG StateChange(Handshaking) client | 6.875 WARN Cannot create stream ConnectionState client | 6.875 DEBUG [Client 963a57627958fad3] Authenticated Ok client | 6.875 DEBUG Write secret available for ApplicationData: SymKey [32]: 8c4c232b3440ceafa3236ea0e762636842f89a263e06fe3c5aa46613417be042 client | 6.875 DEBUG Read secret available for ApplicationData: SymKey [32]: a9774b19f1f0fa390225d45ff7770998d5ad07311a7c8f3784ffb370e86e7184 client | 6.875 DEBUG [Agent 0x556039657420] SSL_AuthCertificateComplete: Ok(()) client | 6.875 DEBUG [Agent 0x556039657420] 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 | 6.875 DEBUG [Client 963a57627958fad3] TLS connection complete client | 6.875 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 6.875 DEBUG [Crypto] Application write key installed client | 6.875 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 6.875 DEBUG [Crypto] application read keys installed client | 6.875 DEBUG [Client 963a57627958fad3] State change from Handshaking -> Connected client | 6.875 INFO [Client 963a57627958fad3] Connection established client | 6.875 DEBUG stream BiDi creatable client | 6.875 INFO Created stream 0 for https://server4/bkzthnoyzm client | 6.875 INFO Saving https://server4/bkzthnoyzm to "/downloads/bkzthnoyzm" client | 6.875 DEBUG StateChange(Connected) client | 6.875 DEBUG stream 0 writable client | 6.875 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.875 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 6.875 DEBUG CRYPTO for hs offset=0, len=36 client | 6.875 DEBUG [Client 963a57627958fad3] pn=1 type=Handshake pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 6.875 DEBUG packet_sent this=0x556039619be0, pn=1, ps=73 client | 6.875 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 6.875 DEBUG [Client 963a57627958fad3] pn=0 type=Short pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 119 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 6.875 DEBUG packet_sent this=0x556039619be0, pn=0, ps=46 client | 6.875 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.875 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 6.875 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 6.875 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 6.875 DEBUG [Client 963a57627958fad3] delay duration 126.338588ms client | 6.875 DEBUG Setting timeout of 126.338588ms sim | Corrupting 81 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 31 0xf6 -> 0xf5, corrupted 8/32 (25.0%) sim | Corrupting 119 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 45 0xac -> 0x72, corrupted 9/33 (27.3%) client | 7.003 DEBUG [LossRecovery] PTO timer fired for Handshake client | 7.003 DEBUG [LossRecovery] PTO timer fired for ApplicationData client | 7.003 DEBUG [Client 963a57627958fad3] Lost: Ack(AckToken { space: Handshake, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] }) client | 7.003 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 615, tv_nsec: 154839203 } client | 7.003 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 36 }) client | 7.003 INFO Lost crypto frame space=hs offset=0 length=36 client | 7.003 DEBUG [Client 963a57627958fad3] Lost: Stream(Stream(SendStreamRecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true })) client | 7.003 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Handshake | ApplicationData), paced: false } client | 7.003 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.003 DEBUG CRYPTO for hs offset=0, len=36 client | 7.003 DEBUG [Client 963a57627958fad3] pn=2 type=Handshake pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 79 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 16169, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 7.003 DEBUG packet_sent this=0x556039619be0, pn=2, ps=79 client | 7.003 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.003 DEBUG [Client 963a57627958fad3] pn=1 type=Short pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 125 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 7.003 DEBUG packet_sent this=0x556039619be0, pn=1, ps=46 client | 7.003 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.003 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.003 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.003 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.003 DEBUG [Client 963a57627958fad3] delay duration 252.554964ms client | 7.003 DEBUG Setting timeout of 252.554964ms sim | Corrupting 125 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 33 0xe5 -> 0x25, corrupted 10/34 (29.4%) sim | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 6/23 (26.1%) client | 7.074 WARN [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 7.074 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.074 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.074 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.074 DEBUG [Client 963a57627958fad3] delay duration 182.04883ms client | 7.074 DEBUG Setting timeout of 182.04883ms client | 7.074 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.074 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.074 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.074 DEBUG [Client 963a57627958fad3] delay duration 182.001271ms client | 7.074 DEBUG Setting timeout of 182.001271ms client | 7.257 DEBUG [LossRecovery] PTO timer fired for Handshake client | 7.257 DEBUG [LossRecovery] PTO timer fired for ApplicationData client | 7.257 DEBUG [Client 963a57627958fad3] Lost: Ack(AckToken { space: Handshake, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] }) client | 7.257 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 615, tv_nsec: 282868059 } client | 7.257 DEBUG [Client 963a57627958fad3] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 36 }) client | 7.257 INFO Lost crypto frame space=hs offset=0 length=36 client | 7.257 DEBUG [Client 963a57627958fad3] Lost: Stream(Stream(SendStreamRecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true })) client | 7.257 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Handshake | ApplicationData), paced: false } client | 7.257 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.257 DEBUG CRYPTO for hs offset=0, len=36 client | 7.257 DEBUG [Client 963a57627958fad3] pn=3 type=Handshake pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 81 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 47946, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 7.257 DEBUG packet_sent this=0x556039619be0, pn=3, ps=81 client | 7.257 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.257 DEBUG [Client 963a57627958fad3] pn=2 type=Short pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 127 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 7.257 DEBUG packet_sent this=0x556039619be0, pn=2, ps=46 client | 7.257 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.257 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.257 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.257 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.257 DEBUG [Client 963a57627958fad3] delay duration 505.512289ms client | 7.257 DEBUG Setting timeout of 505.512289ms sim | Forwarding 127 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 10/35 (28.6%) sim | Forwarding 736 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 6/24 (25.0%) client | 7.274 DEBUG [Client 963a57627958fad3] pn=1 type=Handshake pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 736 sim | Corrupting 736 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 40 0xef -> 0xb8, corrupted 7/25 (28.0%) client | -> RX Crypto { offset: 0, len: 698 } client | 7.274 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 615, tv_nsec: 553647964 } client | 7.274 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.274 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.274 DEBUG CRYPTO for hs offset=0, len=36 client | 7.274 DEBUG [Client 963a57627958fad3] pn=4 type=Handshake pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 78 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 6, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 7.274 DEBUG packet_sent this=0x556039619be0, pn=4, ps=78 client | 7.274 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.274 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.274 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.274 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.274 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.274 DEBUG [Client 963a57627958fad3] delay duration 505.670795ms client | 7.274 DEBUG Setting timeout of 505.670795ms client | 7.274 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.274 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.274 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.274 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.274 DEBUG [Client 963a57627958fad3] delay duration 505.644696ms client | 7.274 DEBUG Setting timeout of 505.644696ms sim | Forwarding 78 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 10/36 (27.8%) sim | Corrupting 21 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 19 0x26 -> 0xd7, corrupted 8/26 (30.8%) sim | Forwarding 203 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 8/27 (29.6%) client | 7.291 DEBUG [Client 963a57627958fad3] pn=1 type=Short pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 203 client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [100, 198, 37, 154, 102, 54, 82, 234], stateless_reset_token: [13, 136, 102, 233, 93, 47, 90, 163, 25, 228, 110, 121, 154, 84, 175, 220] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [205, 146, 96, 208, 129, 93, 100, 76], stateless_reset_token: [138, 104, 127, 217, 237, 0, 231, 158, 247, 14, 119, 89, 185, 174, 43, 245] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [223, 44, 127, 36, 125, 140, 157, 41], stateless_reset_token: [234, 153, 217, 113, 198, 3, 36, 221, 162, 31, 64, 241, 191, 14, 142, 76] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [34, 115, 120, 108, 199, 54, 1, 189], stateless_reset_token: [248, 162, 41, 79, 181, 46, 222, 17, 207, 193, 30, 148, 250, 59, 227, 150] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [195, 157, 96, 149, 92, 122, 123, 103], stateless_reset_token: [182, 146, 32, 39, 215, 206, 255, 207, 155, 199, 37, 189, 232, 103, 151, 222] } client | -> RX Crypto { offset: 0, len: 42 } client | 7.291 DEBUG Read Ok(42) bytes client | 7.291 DEBUG [0x556039657420] Got resumption token [712]: 02000633fcb9b937..3123c919ea79d0b4 client | 7.291 DEBUG [Agent 0x556039657420] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 7.291 DEBUG setting max_stream_data to 1000000 client | 7.291 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 615, tv_nsec: 571018361 } client | 7.291 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.291 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.291 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.291 DEBUG [Client 963a57627958fad3] pn=3 type=Short pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 31 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 34, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 7.291 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.291 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.291 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.291 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.291 DEBUG [Client 963a57627958fad3] delay duration 488.085036ms client | 7.291 DEBUG Setting timeout of 488.085036ms client | 7.291 DEBUG stream BiDi creatable client | 7.291 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.291 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.291 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.291 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.291 DEBUG [Client 963a57627958fad3] delay duration 488.057695ms client | 7.291 DEBUG Setting timeout of 488.057695ms sim | Forwarding 1055 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 8/28 (28.6%) client | 7.293 DEBUG [Client 963a57627958fad3] pn=2 type=Short pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1055 client | -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true } client | -> RX Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX MaxStreams { stream_type: BiDi, maximum_streams: 101 } client | 7.294 DEBUG [Client 963a57627958fad3] Rx ACK space=ap, ranges=[2..=2] client | 7.294 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 client | 7.294 DEBUG packet_lost this=0x556039619be0, pn=0, ps=46 client | 7.294 DEBUG packet_lost this=0x556039619be0, pn=1, ps=46 client | 7.294 DEBUG [Cubic [last_max_cwnd: 7449.4, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: None] CongCtrl 357/6134 ssthresh 6134] Cong event -> recovery; cwnd 6134, ssthresh 6134 client | 7.294 DEBUG [Cubic [last_max_cwnd: 7449.4, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: None] CongCtrl 357/6134 ssthresh 6134] state -> RecoveryStart client | 7.294 DEBUG on_packets_lost this=0x556039619be0, bytes_in_flight=357, cwnd=6134, state=RecoveryStart client | 7.294 DEBUG [Cubic [last_max_cwnd: 7449.4, k: 1.379741855391496, w_max: 7449.4, ca_epoch_start: Some(Instant { tv_sec: 615, tv_nsec: 573579950 })] CongCtrl 311/6134 ssthresh 6134] on_packets_acked this=0x556039619be0, limited=0, bytes_in_flight=311, cwnd=6134, state=RecoveryStart, new_acked=0 client | 7.294 DEBUG [Client 963a57627958fad3] Lost: Stream(Stream(SendStreamRecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true })) client | 7.294 DEBUG [Client 963a57627958fad3] Lost: Stream(Stream(SendStreamRecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true })) client | 7.294 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 615, tv_nsec: 593579950 } client | 7.294 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.294 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.294 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.294 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.294 DEBUG [Client 963a57627958fad3] delay duration 19.897449ms client | 7.294 DEBUG Setting timeout of 19.897449ms client | 7.294 DEBUG stream 0 complete client | 7.294 DEBUG stream BiDi creatable client | 7.294 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.294 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.294 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.294 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.294 DEBUG [Client 963a57627958fad3] delay duration 19.805915ms client | 7.294 DEBUG Setting timeout of 19.805915ms client | 7.294 DEBUG [Client 963a57627958fad3] State change from Connected -> Closing { error: Application(0), timeout: Instant { tv_sec: 615, tv_nsec: 904715236 } } client | 7.294 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 615, tv_nsec: 904715236 } }) client | 7.294 DEBUG [Client 963a57627958fad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.294 DEBUG Building Handshake dcid Some(CID [8]: ad27c986f6f9eaf1) scid Some(CID [0]: ) client | 7.294 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 615, tv_nsec: 573807687 } client | 7.294 DEBUG [Client 963a57627958fad3] pn=5 type=Handshake pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 2519, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 7.294 DEBUG Building Short dcid Some(CID [8]: ad27c986f6f9eaf1) client | 7.294 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 615, tv_nsec: 573807687 } client | 7.294 DEBUG [Client 963a57627958fad3] pn=4 type=Short pri-path:ad27c986f6f9eaf1 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 86 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 28, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.294 DEBUG Setting timeout of 330.826687ms sim | Corrupting 31 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 30 0x5d -> 0x8a, corrupted 11/37 (29.7%) sim | Forwarding 86 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 11/38 (28.9%) client | 7.626 DEBUG [Client 963a57627958fad3] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 615, tv_nsec: 904715236 } } -> Closed(Application(0)) client | 7.626 INFO Closing timer expired client | 7.626 DEBUG Output::None client | 7.626 WARN Unhandled event StateChange(Closed(Application(0))) client | 7.626 DEBUG Timer fired while closed client | 7.626 DEBUG Output::None client | 7.627 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=dcf3b19f4c7a570c client | 7.627 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 7.627 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 client | 7.627 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 7.627 DEBUG [unv-path 0.0.0.0:53438->193.167.100.100:443] Make permanent client | 7.627 DEBUG [unv-path:dcf3b19f4c7a570c 0.0.0.0:53438->193.167.100.100:443] set as primary path client | 7.627 DEBUG [pri-unv-path:dcf3b19f4c7a570c 0.0.0.0:53438->193.167.100.100:443] Path validated Instant { tv_sec: 615, tv_nsec: 906600090 } client | 7.627 DEBUG [Client dcf3b19f4c7a570c] client_start client | 7.627 DEBUG Writing transport parameters, msg=1 client | 7.627 DEBUG [Agent 0x55603965a770] state -> InProgress client | 7.627 DEBUG [Client dcf3b19f4c7a570c] State change from Init -> WaitInitial client | 7.627 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.627 DEBUG Building Initial dcid Some(CID [8]: dcf3b19f4c7a570c) scid Some(CID [0]: ) client | 7.627 DEBUG CRYPTO for in offset=0, len=1213 client | 7.627 DEBUG [Client dcf3b19f4c7a570c] pn=0 type=Initial pri-path:dcf3b19f4c7a570c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 7.627 DEBUG packet_sent this=0x556039619be0, pn=0, ps=1252 client | 7.627 DEBUG ECN probing: sent 1 probes client | 7.628 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.628 DEBUG Building Initial dcid Some(CID [8]: dcf3b19f4c7a570c) scid Some(CID [0]: ) client | 7.628 DEBUG CRYPTO for in offset=1213, len=326 client | 7.628 DEBUG [Client dcf3b19f4c7a570c] pn=1 type=Initial pri-path:dcf3b19f4c7a570c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 7.628 DEBUG [Client dcf3b19f4c7a570c] pad Initial from 366 to PLPMTU 1252 client | 7.628 DEBUG packet_sent this=0x556039619be0, pn=1, ps=1252 client | 7.628 DEBUG ECN probing: sent 2 probes client | 7.628 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 7.628 DEBUG Building Initial dcid Some(CID [8]: dcf3b19f4c7a570c) scid Some(CID [0]: ) client | 7.628 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 7.628 DEBUG [Client dcf3b19f4c7a570c] delay duration 3.901339ms client | 7.628 DEBUG Setting timeout of 3.901339ms client | 7.628 DEBUG StateChange(WaitInitial) client | 7.628 WARN Cannot create stream ConnectionState client | 7.628 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 7.628 DEBUG Building Initial dcid Some(CID [8]: dcf3b19f4c7a570c) scid Some(CID [0]: ) client | 7.628 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 7.628 DEBUG [Client dcf3b19f4c7a570c] delay duration 3.876032ms client | 7.628 DEBUG Setting timeout of 3.876032ms client | 7.633 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.633 DEBUG Building Initial dcid Some(CID [8]: dcf3b19f4c7a570c) scid Some(CID [0]: ) client | 7.633 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.633 DEBUG [Client dcf3b19f4c7a570c] delay duration 294.812689ms client | 7.633 DEBUG Setting timeout of 294.812689ms sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 11/39 (28.2%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 11/40 (27.5%) sim | Forwarding 1201 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 8/29 (27.6%) client | 7.666 DEBUG [Client dcf3b19f4c7a570c] pn=0 type=Initial pri-path:dcf3b19f4c7a570c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1201 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX Padding { len: 1161 } client | 7.666 DEBUG [Client dcf3b19f4c7a570c] Rx ACK space=in, ranges=[0..=0] client | 7.666 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 client | 7.666 DEBUG on_packets_acked this=0x556039619be0, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252 client | 7.666 DEBUG Acked crypto frame space=in offset=0 length=1213 client | 7.666 DEBUG [Client dcf3b19f4c7a570c] Changing to use Server CID=016a6d2f2cbaf144 client | 7.666 DEBUG [Client dcf3b19f4c7a570c] State change from WaitInitial -> WaitVersion client | 7.666 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.666 DEBUG Building Initial dcid Some(CID [8]: 016a6d2f2cbaf144) scid Some(CID [0]: ) client | 7.666 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.666 DEBUG [Client dcf3b19f4c7a570c] delay duration 77.635377ms client | 7.666 DEBUG Setting timeout of 77.635377ms client | 7.666 WARN Unhandled event StateChange(WaitVersion) client | 7.666 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.666 DEBUG Building Initial dcid Some(CID [8]: 016a6d2f2cbaf144) scid Some(CID [0]: ) client | 7.666 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.666 DEBUG [Client dcf3b19f4c7a570c] delay duration 77.595603ms client | 7.666 DEBUG Setting timeout of 77.595603ms sim | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 8/30 (26.7%) client | 7.670 DEBUG [Client dcf3b19f4c7a570c] pn=1 type=Initial pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 463 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Padding { len: 329 } client | 7.670 DEBUG [Client dcf3b19f4c7a570c] Rx ACK space=in, ranges=[0..=1] client | 7.670 DEBUG [LossRecovery] ACK for Initial - largest_acked=1 client | 7.670 DEBUG on_packets_acked this=0x556039619be0, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=1252 client | 7.670 DEBUG Acked crypto frame space=in offset=1213 length=326 client | 7.670 DEBUG Read Ok(90) bytes client | 7.671 DEBUG Write secret available for Handshake: SymKey [32]: b919ea54ee848219d7ecb08933f4112d944923455795cc03ddf64bee5cb739bf client | 7.671 DEBUG Read secret available for Handshake: SymKey [32]: af4326d3ff143b9ab1949c3202f01b4236465251428bf783b1bf46ad0e84048b client | 7.671 DEBUG [Agent 0x55603965a770] state -> InProgress client | 7.671 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 7.671 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 7.671 DEBUG [Crypto] Handshake keys installed client | 7.671 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 615, tv_nsec: 950433287 } client | 7.671 DEBUG [Client dcf3b19f4c7a570c] State change from WaitVersion -> Handshaking client | 7.671 DEBUG [Client dcf3b19f4c7a570c] pn=0 type=Handshake pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 737 client | -> RX Crypto { offset: 0, len: 699 } client | 7.671 DEBUG Read Ok(699) bytes client | 7.671 DEBUG [Agent 0x55603965a770] state -> AuthenticationPending client | 7.671 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 615, tv_nsec: 950433287 } client | 7.671 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.671 DEBUG Building Initial dcid Some(CID [8]: 016a6d2f2cbaf144) scid Some(CID [0]: ) client | 7.671 DEBUG [Client dcf3b19f4c7a570c] pn=2 type=Initial pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 41 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 138, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 7.671 DEBUG Building Handshake dcid Some(CID [8]: 016a6d2f2cbaf144) scid Some(CID [0]: ) client | 7.671 DEBUG [Client dcf3b19f4c7a570c] pn=0 type=Handshake pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 81 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 138, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 7.671 DEBUG [Client dcf3b19f4c7a570c] Drop packet number space in client | 7.672 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 7.672 WARN [LossRecovery] ignoring in-2 from dropped space client | 7.672 DEBUG ECN probing: sent 3 probes client | 7.672 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.672 DEBUG Building Handshake dcid Some(CID [8]: 016a6d2f2cbaf144) scid Some(CID [0]: ) client | 7.672 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.672 DEBUG [Client dcf3b19f4c7a570c] delay duration 101.126104ms client | 7.672 DEBUG Setting timeout of 101.126104ms client | 7.672 DEBUG StateChange(Handshaking) client | 7.672 WARN Cannot create stream ConnectionState client | 7.672 DEBUG [Client dcf3b19f4c7a570c] Authenticated Ok client | 7.672 DEBUG Write secret available for ApplicationData: SymKey [32]: 2eded5c343cd66160b398ab096180c9e9201d36e192d46132467fa40cbb9221d client | 7.672 DEBUG Read secret available for ApplicationData: SymKey [32]: e16497b69bc07d1c518304ba25b8290c53dfa86957c936ac907990a2a14028a6 client | 7.672 DEBUG [Agent 0x55603965a770] SSL_AuthCertificateComplete: Ok(()) client | 7.672 DEBUG [Agent 0x55603965a770] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 7.672 DEBUG [Client dcf3b19f4c7a570c] TLS connection complete client | 7.672 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 7.672 DEBUG [Crypto] Application write key installed client | 7.672 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 7.672 DEBUG [Crypto] application read keys installed client | 7.672 DEBUG [Client dcf3b19f4c7a570c] State change from Handshaking -> Connected client | 7.672 INFO [Client dcf3b19f4c7a570c] Connection established client | 7.672 DEBUG stream BiDi creatable client | 7.672 INFO Created stream 0 for https://server4/cknsljnpbk client | 7.672 INFO Saving https://server4/cknsljnpbk to "/downloads/cknsljnpbk" client | 7.672 DEBUG StateChange(Connected) client | 7.672 DEBUG stream 0 writable client | 7.672 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.672 DEBUG Building Handshake dcid Some(CID [8]: 016a6d2f2cbaf144) scid Some(CID [0]: ) client | 7.672 DEBUG CRYPTO for hs offset=0, len=36 client | 7.672 DEBUG [Client dcf3b19f4c7a570c] pn=1 type=Handshake pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 7.672 DEBUG packet_sent this=0x556039619be0, pn=1, ps=73 client | 7.672 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.672 DEBUG [Client dcf3b19f4c7a570c] pn=0 type=Short pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 119 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 7.672 DEBUG packet_sent this=0x556039619be0, pn=0, ps=46 client | 7.672 DEBUG ECN probing: sent 4 probes client | 7.672 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.672 DEBUG Building Handshake dcid Some(CID [8]: 016a6d2f2cbaf144) scid Some(CID [0]: ) client | 7.672 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.672 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.672 DEBUG [Client dcf3b19f4c7a570c] delay duration 101.162102ms client | 7.672 DEBUG Setting timeout of 101.162102ms sim | Corrupting 81 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 12/41 (29.3%) sim | Forwarding 119 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 12/42 (28.6%) sim | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 8/31 (25.8%) client | 7.705 DEBUG [Client dcf3b19f4c7a570c] pn=0 type=Short pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 204 client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [110, 58, 237, 147, 67, 155, 12, 128], stateless_reset_token: [201, 33, 133, 76, 39, 151, 115, 220, 136, 195, 66, 14, 233, 89, 179, 73] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [82, 133, 35, 110, 72, 224, 158, 152], stateless_reset_token: [19, 6, 115, 1, 88, 149, 112, 107, 197, 190, 251, 50, 53, 34, 20, 21] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [232, 1, 165, 254, 140, 75, 197, 128], stateless_reset_token: [161, 92, 201, 9, 228, 31, 208, 2, 177, 143, 91, 69, 15, 136, 118, 28] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [2, 217, 104, 165, 54, 55, 236, 1], stateless_reset_token: [200, 77, 251, 140, 100, 17, 140, 156, 162, 148, 110, 112, 58, 167, 14, 62] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [91, 239, 136, 65, 184, 176, 226, 249], stateless_reset_token: [138, 79, 4, 116, 34, 229, 148, 7, 225, 214, 137, 57, 172, 112, 247, 54] } client | -> RX Crypto { offset: 0, len: 42 } client | 7.705 DEBUG [Client dcf3b19f4c7a570c] State change from Connected -> Confirmed client | 7.705 DEBUG PMTUD started with probe size 1380 client | 7.705 DEBUG [Client dcf3b19f4c7a570c] Drop packet number space hs client | 7.705 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 7.705 DEBUG [Client dcf3b19f4c7a570c] No preferred address to migrate to client | 7.705 DEBUG Read Ok(42) bytes client | 7.705 DEBUG [0x55603965a770] Got resumption token [712]: 02000633fcb9bf89..2f6716407fbd46a2 client | 7.705 DEBUG [Agent 0x55603965a770] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 7.705 DEBUG setting max_stream_data to 1000000 client | 7.705 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 616, tv_nsec: 5253500 } client | 7.705 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.705 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.705 DEBUG Sending PMTUD probe of size 1380, count 1 client | 7.705 DEBUG [Client dcf3b19f4c7a570c] pn=1 type=Short pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352 client | TX -> Ping client | TX -> Padding { len: 1325 } client | 7.705 DEBUG packet_sent this=0x556039619be0, pn=1, ps=1352 client | 7.705 DEBUG ECN probing: sent 5 probes client | 7.705 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.705 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.705 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.705 DEBUG [Client dcf3b19f4c7a570c] delay duration 19.670734ms client | 7.705 DEBUG Setting timeout of 19.670734ms client | 7.706 WARN Unhandled event StateChange(Confirmed) client | 7.706 DEBUG stream BiDi creatable client | 7.706 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.706 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.706 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.706 DEBUG [Client dcf3b19f4c7a570c] delay duration 19.633053ms client | 7.706 DEBUG Setting timeout of 19.633053ms sim | Forwarding 1055 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 8/32 (25.0%) client | 7.707 DEBUG [Client dcf3b19f4c7a570c] pn=1 type=Short pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1055 client | -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true } client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX MaxStreams { stream_type: BiDi, maximum_streams: 101 } client | 7.707 DEBUG [Client dcf3b19f4c7a570c] Rx ACK space=ap, ranges=[0..=0] client | 7.707 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 client | 7.707 DEBUG on_packets_acked this=0x556039619be0, limited=1, bytes_in_flight=1352, cwnd=12520, state=SlowStart, new_acked=46 client | 7.707 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 615, tv_nsec: 987410042 } client | 7.707 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.707 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.707 DEBUG [Client dcf3b19f4c7a570c] pn=2 type=Short pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 31 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 6, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 7.707 DEBUG ECN probing: sent 6 probes client | 7.707 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.707 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.707 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.707 DEBUG [Client dcf3b19f4c7a570c] delay duration 112.013832ms client | 7.707 DEBUG Setting timeout of 112.013832ms client | 7.707 DEBUG stream 0 complete client | 7.707 DEBUG stream BiDi creatable client | 7.707 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.708 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.708 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.708 DEBUG [Client dcf3b19f4c7a570c] delay duration 111.941387ms client | 7.708 DEBUG Setting timeout of 111.941387ms client | 7.708 DEBUG [Client dcf3b19f4c7a570c] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 616, tv_nsec: 329796278 } } client | 7.708 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 616, tv_nsec: 329796278 } }) client | 7.708 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.708 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.708 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 615, tv_nsec: 987629565 } client | 7.708 DEBUG [Client dcf3b19f4c7a570c] pn=3 type=Short pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 42 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 27, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.708 DEBUG ECN probing: sent 7 probes client | 7.708 DEBUG Setting timeout of 342.107894ms sim | Corrupting 1352 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 37 0x1c -> 0xf7, corrupted 13/43 (30.2%) sim | Forwarding 31 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 13/44 (29.5%) sim | Forwarding 42 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 13/45 (28.9%) sim | Forwarding 27 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 8/33 (24.2%) client | 7.740 DEBUG [Client dcf3b19f4c7a570c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 7.740 DEBUG Building Short dcid Some(CID [8]: 016a6d2f2cbaf144) client | 7.740 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 616, tv_nsec: 19888677 } client | 7.740 DEBUG [Client dcf3b19f4c7a570c] pn=4 type=Short pri-path:016a6d2f2cbaf144 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 4059, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 7.740 DEBUG ECN probing: sent 8 probes client | 7.740 DEBUG Setting timeout of 309.816811ms client | 7.740 DEBUG Setting timeout of 309.809348ms sim | Forwarding 43 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 13/46 (28.3%) client | 8.051 DEBUG [Client dcf3b19f4c7a570c] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 616, tv_nsec: 329796278 } } -> Closed(Application(0)) client | 8.051 INFO Closing timer expired client | 8.051 DEBUG Output::None client | 8.051 WARN Unhandled event StateChange(Closed(Application(0))) client | 8.051 DEBUG Timer fired while closed client | 8.051 DEBUG Output::None client | 8.052 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=eba4580fd159960aeb952c986c client | 8.052 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 8.052 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 client | 8.052 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 8.052 DEBUG [unv-path 0.0.0.0:53438->193.167.100.100:443] Make permanent client | 8.052 DEBUG [unv-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443] set as primary path client | 8.052 DEBUG [pri-unv-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443] Path validated Instant { tv_sec: 616, tv_nsec: 331678939 } client | 8.052 DEBUG [Client eba4580fd159960aeb952c986c] client_start client | 8.052 DEBUG Writing transport parameters, msg=1 client | 8.052 DEBUG [Agent 0x55603965a770] state -> InProgress client | 8.052 DEBUG [Client eba4580fd159960aeb952c986c] State change from Init -> WaitInitial client | 8.052 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.052 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.052 DEBUG CRYPTO for in offset=0, len=1208 client | 8.052 DEBUG [Client eba4580fd159960aeb952c986c] pn=0 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 8.052 DEBUG packet_sent this=0x556039619be0, pn=0, ps=1252 client | 8.052 DEBUG ECN probing: sent 1 probes client | 8.053 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.053 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.053 DEBUG CRYPTO for in offset=1208, len=331 client | 8.053 DEBUG [Client eba4580fd159960aeb952c986c] pn=1 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 376 client | TX -> Crypto { offset: 1208, len: 331 } client | 8.053 DEBUG [Client eba4580fd159960aeb952c986c] pad Initial from 376 to PLPMTU 1252 client | 8.053 DEBUG packet_sent this=0x556039619be0, pn=1, ps=1252 client | 8.053 DEBUG ECN probing: sent 2 probes client | 8.053 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 8.053 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.053 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 8.053 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 3.950723ms client | 8.053 DEBUG Setting timeout of 3.950723ms client | 8.053 DEBUG StateChange(WaitInitial) client | 8.053 WARN Cannot create stream ConnectionState client | 8.053 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 8.053 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.053 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 8.053 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 3.925105ms client | 8.053 DEBUG Setting timeout of 3.925105ms client | 8.058 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.058 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.058 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.058 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 294.842063ms client | 8.058 DEBUG Setting timeout of 294.842063ms server | QLog: done with dcf3b19f4c7a570c.qlog sim | Corrupting 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 8 0x58 -> 0x20, corrupted 14/47 (29.8%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 14/48 (29.2%) client | 8.354 DEBUG [LossRecovery] PTO timer fired for Initial client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 }) client | 8.354 INFO Lost crypto frame space=in offset=0 length=1208 client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] Lost: EcnEct0 client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 }) client | 8.354 INFO Lost crypto frame space=in offset=1208 length=331 client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] Lost: EcnEct0 client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 8.354 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.354 DEBUG CRYPTO for in offset=0, len=1208 client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] pn=2 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 8.354 DEBUG packet_sent this=0x556039619be0, pn=2, ps=1252 client | 8.354 DEBUG ECN probing: sent 3 probes client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.354 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.354 DEBUG CRYPTO for in offset=1208, len=331 client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] pn=3 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 376 client | TX -> Crypto { offset: 1208, len: 331 } client | 8.354 DEBUG [Client eba4580fd159960aeb952c986c] pad Initial from 376 to PLPMTU 1252 client | 8.354 DEBUG packet_sent this=0x556039619be0, pn=3, ps=1252 client | 8.354 DEBUG ECN probing: sent 4 probes client | 8.355 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.355 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.355 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.355 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 599.921172ms client | 8.355 DEBUG Setting timeout of 599.921172ms sim | Corrupting 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 26 0x37 -> 0x63, corrupted 15/49 (30.6%) sim | Corrupting 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 18 0x6c -> 0xe0, corrupted 16/50 (32.0%) sim | Forwarding 21 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 8/34 (23.5%) client | 8.555 DEBUG saving datagram of 21 bytes client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.556 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.556 DEBUG CRYPTO for in offset=0, len=1208 client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] pn=4 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 8.556 DEBUG packet_sent this=0x556039619be0, pn=4, ps=1252 client | 8.556 DEBUG ECN probing: sent 5 probes client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.556 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.556 DEBUG CRYPTO for in offset=1208, len=331 client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] pn=5 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, Ect0) len 376 client | TX -> Crypto { offset: 1208, len: 331 } client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] pad Initial from 376 to PLPMTU 1252 client | 8.556 DEBUG packet_sent this=0x556039619be0, pn=5, ps=1252 client | 8.556 DEBUG ECN probing: sent 6 probes client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.556 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.556 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 599.918167ms client | 8.556 DEBUG Setting timeout of 599.918167ms client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.556 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 8.556 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 8.556 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 599.890476ms client | 8.556 DEBUG Setting timeout of 599.890476ms sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 16/51 (31.4%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 16/52 (30.8%) client | 9.157 DEBUG [LossRecovery] PTO timer fired for Initial client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 }) client | 9.157 INFO Lost crypto frame space=in offset=0 length=1208 client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] Lost: EcnEct0 client | 9.157 DEBUG ECN validation failed, all 3 initial marked packets were lost client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 }) client | 9.157 INFO Lost crypto frame space=in offset=1208 length=331 client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] Lost: EcnEct0 client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 9.157 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 9.157 DEBUG CRYPTO for in offset=0, len=1208 client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] pn=6 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 9.157 DEBUG packet_sent this=0x556039619be0, pn=6, ps=1252 client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 9.157 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 9.157 DEBUG CRYPTO for in offset=1208, len=331 client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] pn=7 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 376 client | TX -> Crypto { offset: 1208, len: 331 } client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] pad Initial from 376 to PLPMTU 1252 client | 9.157 DEBUG packet_sent this=0x556039619be0, pn=7, ps=1252 client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 9.157 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 9.157 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 9.157 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 1.199928045s client | 9.157 DEBUG Setting timeout of 1.199928045s sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 16/53 (30.2%) sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 16/54 (29.6%) client | 10.358 DEBUG [LossRecovery] PTO timer fired for Initial client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 }) client | 10.358 INFO Lost crypto frame space=in offset=1208 length=331 client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] Lost: EcnEct0 client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 }) client | 10.358 INFO Lost crypto frame space=in offset=0 length=1208 client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 10.358 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 10.358 DEBUG CRYPTO for in offset=0, len=1208 client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] pn=8 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 10.358 DEBUG packet_sent this=0x556039619be0, pn=8, ps=1252 client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 10.358 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 10.358 DEBUG CRYPTO for in offset=1208, len=331 client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] pn=9 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 376 client | TX -> Crypto { offset: 1208, len: 331 } client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] pad Initial from 376 to PLPMTU 1252 client | 10.358 DEBUG packet_sent this=0x556039619be0, pn=9, ps=1252 client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 10.358 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 10.358 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 10.358 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 2.399932834s client | 10.358 DEBUG Setting timeout of 2.399932834s sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 16/55 (29.1%) sim | Corrupting 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443 offset 41 0x75 -> 0x66, corrupted 17/56 (30.4%) sim | Corrupting 21 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 1 0x74 -> 0xb1, corrupted 9/35 (25.7%) sim | Corrupting 21 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 12 0xd1 -> 0x55, corrupted 10/36 (27.8%) client | 12.760 DEBUG [LossRecovery] PTO timer fired for Initial client | 12.760 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 }) client | 12.760 INFO Lost crypto frame space=in offset=0 length=1208 client | 12.760 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 }) client | 12.760 INFO Lost crypto frame space=in offset=1208 length=331 client | 12.760 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 12.760 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 12.760 DEBUG CRYPTO for in offset=0, len=1208 client | 12.760 DEBUG [Client eba4580fd159960aeb952c986c] pn=10 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 12.760 DEBUG packet_sent this=0x556039619be0, pn=10, ps=1252 client | 12.760 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 12.760 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 12.760 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 12.760 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 4.799676105s client | 12.760 DEBUG Setting timeout of 4.799676105s sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 17/57 (29.8%) sim | Forwarding 21 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 10/37 (27.0%) client | 16.134 DEBUG saving datagram of 21 bytes client | 16.134 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 16.134 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 16.134 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 16.134 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 1.425903138s client | 16.134 DEBUG Setting timeout of 1.425903138s client | 16.134 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 16.134 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 16.134 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 16.134 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 1.425860869s client | 16.134 DEBUG Setting timeout of 1.425860869s sim | Corrupting 21 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 15 0x54 -> 0xab, corrupted 11/38 (28.9%) client | 17.561 DEBUG [LossRecovery] PTO timer fired for Initial client | 17.561 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 }) client | 17.561 INFO Lost crypto frame space=in offset=0 length=1208 client | 17.561 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 17.561 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 17.561 DEBUG CRYPTO for in offset=0, len=1208 client | 17.561 DEBUG [Client eba4580fd159960aeb952c986c] pn=11 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 17.561 DEBUG packet_sent this=0x556039619be0, pn=11, ps=1252 client | 17.561 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 17.561 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 17.561 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 17.561 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 9.599760292s client | 17.561 DEBUG Setting timeout of 9.599760292s sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 17/58 (29.3%) sim | Corrupting 21 bytes 193.167.100.100:443 -> 193.167.0.100:53438 offset 2 0xc5 -> 0xb0, corrupted 12/39 (30.8%) sim | Forwarding 21 bytes 193.167.100.100:443 -> 193.167.0.100:53438, corrupted 12/40 (30.0%) client | 26.240 DEBUG saving datagram of 21 bytes client | 26.240 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 26.240 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 26.240 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 26.240 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 921.170808ms client | 26.240 DEBUG Setting timeout of 921.170808ms client | 26.240 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 26.240 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 26.240 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 26.240 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 921.133899ms client | 26.240 DEBUG Setting timeout of 921.133899ms client | 27.162 DEBUG [LossRecovery] PTO timer fired for Initial client | 27.162 DEBUG [Client eba4580fd159960aeb952c986c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 }) client | 27.162 INFO Lost crypto frame space=in offset=0 length=1208 client | 27.162 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 27.162 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 27.162 DEBUG CRYPTO for in offset=0, len=1208 client | 27.162 DEBUG [Client eba4580fd159960aeb952c986c] pn=12 type=Initial pri-path:eba4580fd159960aeb952c986c 0.0.0.0:53438->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1208 } client | 27.162 DEBUG packet_sent this=0x556039619be0, pn=12, ps=1252 client | 27.162 DEBUG [Client eba4580fd159960aeb952c986c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 27.162 DEBUG Building Initial dcid Some(CID [13]: eba4580fd159960aeb952c986c) scid Some(CID [0]: ) client | 27.162 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } client | 27.162 DEBUG [Client eba4580fd159960aeb952c986c] delay duration 10.890010372s client | 27.162 DEBUG Setting timeout of 10.890010372s sim | Forwarding 1252 bytes 193.167.0.100:53438 -> 193.167.100.100:443, corrupted 17/59 (28.8%) server | QLog: done with 963a57627958fad3.qlog client | 38.053 INFO [Client eba4580fd159960aeb952c986c] idle timeout expired client | 38.053 DEBUG [Client eba4580fd159960aeb952c986c] State change from WaitInitial -> Closed(Transport(IdleTimeout)) client | 38.053 DEBUG Output::None client | 38.053 WARN Unhandled event StateChange(Closed(Transport(IdleTimeout))) client | 38.053 DEBUG Timer fired while closed client | 38.053 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