2025-04-30 11:27:24,575 Generated random file: lqozzoyqyu of size: 1024 2025-04-30 11:27:24,575 Generated random file: xwvqxvyfvh of size: 1024 2025-04-30 11:27:24,576 Generated random file: lmzqqpvqsn of size: 1024 2025-04-30 11:27:24,576 Generated random file: xxzfwtwqjx of size: 1024 2025-04-30 11:27:24,576 Generated random file: dwlypjxaek of size: 1024 2025-04-30 11:27:24,576 Generated random file: vxtmdluioi of size: 1024 2025-04-30 11:27:24,576 Generated random file: dtgfmdtmxa of size: 1024 2025-04-30 11:27:24,576 Generated random file: cuymalyvth of size: 1024 2025-04-30 11:27:24,576 Generated random file: wjbuoxifuu of size: 1024 2025-04-30 11:27:24,576 Generated random file: ecashyjvix of size: 1024 2025-04-30 11:27:24,576 Generated random file: ygdzkqqdxc of size: 1024 2025-04-30 11:27:24,577 Generated random file: ijymnfcutd of size: 1024 2025-04-30 11:27:24,577 Generated random file: ctamjbsgig of size: 1024 2025-04-30 11:27:24,577 Generated random file: gorylarxet of size: 1024 2025-04-30 11:27:24,577 Generated random file: qnzgkkurpp of size: 1024 2025-04-30 11:27:24,577 Generated random file: sefjyistga of size: 1024 2025-04-30 11:27:24,577 Generated random file: qtylywgeyd of size: 1024 2025-04-30 11:27:24,577 Generated random file: ozndeigwpb of size: 1024 2025-04-30 11:27:24,577 Generated random file: knsxehylbo of size: 1024 2025-04-30 11:27:24,578 Generated random file: hludfcnbex of size: 1024 2025-04-30 11:27:24,578 Generated random file: ussrwegdxc of size: 1024 2025-04-30 11:27:24,578 Generated random file: ioijsvksem of size: 1024 2025-04-30 11:27:24,578 Generated random file: pddfwvtssy of size: 1024 2025-04-30 11:27:24,578 Generated random file: mdwvfqwmhx of size: 1024 2025-04-30 11:27:24,578 Generated random file: qqpbthifbe of size: 1024 2025-04-30 11:27:24,578 Generated random file: jjarhrywds of size: 1024 2025-04-30 11:27:24,578 Generated random file: xabeusdgnv of size: 1024 2025-04-30 11:27:24,578 Generated random file: epqlszbyau of size: 1024 2025-04-30 11:27:24,579 Generated random file: mrryvjvuvp of size: 1024 2025-04-30 11:27:24,579 Generated random file: lzukkmexqf of size: 1024 2025-04-30 11:27:24,579 Generated random file: deemfozdkc of size: 1024 2025-04-30 11:27:24,579 Generated random file: bejmhwshgu of size: 1024 2025-04-30 11:27:24,579 Generated random file: bgdrprikah of size: 1024 2025-04-30 11:27:24,579 Generated random file: epenlzhtjm of size: 1024 2025-04-30 11:27:24,579 Generated random file: zulygxpurk of size: 1024 2025-04-30 11:27:24,579 Generated random file: cmjbntkydh of size: 1024 2025-04-30 11:27:24,580 Generated random file: wbctlummir of size: 1024 2025-04-30 11:27:24,580 Generated random file: owcffcjsup of size: 1024 2025-04-30 11:27:24,580 Generated random file: rbjbczpbzr of size: 1024 2025-04-30 11:27:24,580 Generated random file: paevdrdmfu of size: 1024 2025-04-30 11:27:24,580 Generated random file: wawxzfsbxa of size: 1024 2025-04-30 11:27:24,580 Generated random file: ccfzbmixkf of size: 1024 2025-04-30 11:27:24,580 Generated random file: oopyqgzisv of size: 1024 2025-04-30 11:27:24,580 Generated random file: avdspknxqn of size: 1024 2025-04-30 11:27:24,580 Generated random file: uvlssdfyge of size: 1024 2025-04-30 11:27:24,581 Generated random file: ntrnzslixo of size: 1024 2025-04-30 11:27:24,581 Generated random file: uqcviqptlw of size: 1024 2025-04-30 11:27:24,581 Generated random file: inxputlmqi of size: 1024 2025-04-30 11:27:24,581 Generated random file: qjerlkoxqp of size: 1024 2025-04-30 11:27:24,581 Generated random file: zoxpqzfmss of size: 1024 2025-04-30 11:27:24,581 Requests: https://server4:443/lqozzoyqyu https://server4:443/xwvqxvyfvh https://server4:443/lmzqqpvqsn https://server4:443/xxzfwtwqjx https://server4:443/dwlypjxaek https://server4:443/vxtmdluioi https://server4:443/dtgfmdtmxa https://server4:443/cuymalyvth https://server4:443/wjbuoxifuu https://server4:443/ecashyjvix https://server4:443/ygdzkqqdxc https://server4:443/ijymnfcutd https://server4:443/ctamjbsgig https://server4:443/gorylarxet https://server4:443/qnzgkkurpp https://server4:443/sefjyistga https://server4:443/qtylywgeyd https://server4:443/ozndeigwpb https://server4:443/knsxehylbo https://server4:443/hludfcnbex https://server4:443/ussrwegdxc https://server4:443/ioijsvksem https://server4:443/pddfwvtssy https://server4:443/mdwvfqwmhx https://server4:443/qqpbthifbe https://server4:443/jjarhrywds https://server4:443/xabeusdgnv https://server4:443/epqlszbyau https://server4:443/mrryvjvuvp https://server4:443/lzukkmexqf https://server4:443/deemfozdkc https://server4:443/bejmhwshgu https://server4:443/bgdrprikah https://server4:443/epenlzhtjm https://server4:443/zulygxpurk https://server4:443/cmjbntkydh https://server4:443/wbctlummir https://server4:443/owcffcjsup https://server4:443/rbjbczpbzr https://server4:443/paevdrdmfu https://server4:443/wawxzfsbxa https://server4:443/ccfzbmixkf https://server4:443/oopyqgzisv https://server4:443/avdspknxqn https://server4:443/uvlssdfyge https://server4:443/ntrnzslixo https://server4:443/uqcviqptlw https://server4:443/inxputlmqi https://server4:443/qjerlkoxqp https://server4:443/zoxpqzfmss 2025-04-30 11:27:24,639 2025-04-30 11:27:24,639 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_r33xaf79/ TESTCASE_SERVER=multiconnect TESTCASE_CLIENT=multiconnect WWW=/tmp/www_1uvwsehf/ DOWNLOADS=/tmp/download_5zr8w78h/ SERVER_LOGS=/tmp/logs_server_2vufoglo CLIENT_LOGS=/tmp/logs_client_gau5p8fu 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/facebook/proxygen/mvfst-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/lqozzoyqyu https://server4:443/xwvqxvyfvh https://server4:443/lmzqqpvqsn https://server4:443/xxzfwtwqjx https://server4:443/dwlypjxaek https://server4:443/vxtmdluioi https://server4:443/dtgfmdtmxa https://server4:443/cuymalyvth https://server4:443/wjbuoxifuu https://server4:443/ecashyjvix https://server4:443/ygdzkqqdxc https://server4:443/ijymnfcutd https://server4:443/ctamjbsgig https://server4:443/gorylarxet https://server4:443/qnzgkkurpp https://server4:443/sefjyistga https://server4:443/qtylywgeyd https://server4:443/ozndeigwpb https://server4:443/knsxehylbo https://server4:443/hludfcnbex https://server4:443/ussrwegdxc https://server4:443/ioijsvksem https://server4:443/pddfwvtssy https://server4:443/mdwvfqwmhx https://server4:443/qqpbthifbe https://server4:443/jjarhrywds https://server4:443/xabeusdgnv https://server4:443/epqlszbyau https://server4:443/mrryvjvuvp https://server4:443/lzukkmexqf https://server4:443/deemfozdkc https://server4:443/bejmhwshgu https://server4:443/bgdrprikah https://server4:443/epenlzhtjm https://server4:443/zulygxpurk https://server4:443/cmjbntkydh https://server4:443/wbctlummir https://server4:443/owcffcjsup https://server4:443/rbjbczpbzr https://server4:443/paevdrdmfu https://server4:443/wawxzfsbxa https://server4:443/ccfzbmixkf https://server4:443/oopyqgzisv https://server4:443/avdspknxqn https://server4:443/uvlssdfyge https://server4:443/ntrnzslixo https://server4:443/uqcviqptlw https://server4:443/inxputlmqi https://server4:443/qjerlkoxqp https://server4:443/zoxpqzfmss" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 11:27:53,445 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | + set -o nounset client | + VERSION=1 client | + HQ_CLI=/proxygen/_build/proxygen/bin/hq client | + PORT=443 client | + LOGLEVEL=2 client | + /setup.sh client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 client | + PROTOCOL=hq-interop client | + HTTPVERSION=0.9 client | + CONN_FLOW_CONTROL=107374182 client | + STREAM_FLOW_CONTROL=107374182 client | ++ echo 'https://server4:443/lqozzoyqyu https://server4:443/xwvqxvyfvh https://server4:443/lmzqqpvqsn https://server4:443/xxzfwtwqjx https://server4:443/dwlypjxaek https://server4:443/vxtmdluioi https://server4:443/dtgfmdtmxa https://server4:443/cuymalyvth https://server4:443/wjbuoxifuu https://server4:443/ecashyjvix https://server4:443/ygdzkqqdxc https://server4:443/ijymnfcutd https://server4:443/ctamjbsgig https://server4:443/gorylarxet https://server4:443/qnzgkkurpp https://server4:443/sefjyistga https://server4:443/qtylywgeyd https://server4:443/ozndeigwpb https://server4:443/knsxehylbo https://server4:443/hludfcnbex https://server4:443/ussrwegdxc https://server4:443/ioijsvksem https://server4:443/pddfwvtssy https://server4:443/mdwvfqwmhx https://server4:443/qqpbthifbe https://server4:443/jjarhrywds https://server4:443/xabeusdgnv https://server4:443/epqlszbyau https://server4:443/mrryvjvuvp https://server4:443/lzukkmexqf https://server4:443/deemfozdkc https://server4:443/bejmhwshgu https://server4:443/bgdrprikah https://server4:443/epenlzhtjm https://server4:443/zulygxpurk https://server4:443/cmjbntkydh https://server4:443/wbctlummir https://server4:443/owcffcjsup https://server4:443/rbjbczpbzr https://server4:443/paevdrdmfu https://server4:443/wawxzfsbxa https://server4:443/ccfzbmixkf https://server4:443/oopyqgzisv https://server4:443/avdspknxqn https://server4:443/uvlssdfyge https://server4:443/ntrnzslixo https://server4:443/uqcviqptlw https://server4:443/inxputlmqi https://server4:443/qjerlkoxqp https://server4:443/zoxpqzfmss' client | ++ tr ' ' '\n' client | ++ awk -F / '{ print "/" $4 }' client | ++ paste -sd, client | + INVOCATIONS=/lqozzoyqyu,/xwvqxvyfvh,/lmzqqpvqsn,/xxzfwtwqjx,/dwlypjxaek,/vxtmdluioi,/dtgfmdtmxa,/cuymalyvth,/wjbuoxifuu,/ecashyjvix,/ygdzkqqdxc,/ijymnfcutd,/ctamjbsgig,/gorylarxet,/qnzgkkurpp,/sefjyistga,/qtylywgeyd,/ozndeigwpb,/knsxehylbo,/hludfcnbex,/ussrwegdxc,/ioijsvksem,/pddfwvtssy,/mdwvfqwmhx,/qqpbthifbe,/jjarhrywds,/xabeusdgnv,/epqlszbyau,/mrryvjvuvp,/lzukkmexqf,/deemfozdkc,/bejmhwshgu,/bgdrprikah,/epenlzhtjm,/zulygxpurk,/cmjbntkydh,/wbctlummir,/owcffcjsup,/rbjbczpbzr,/paevdrdmfu,/wawxzfsbxa,/ccfzbmixkf,/oopyqgzisv,/avdspknxqn,/uvlssdfyge,/ntrnzslixo,/uqcviqptlw,/inxputlmqi,/qjerlkoxqp,/zoxpqzfmss client | + EARLYDATA=false client | + PSK_FILE= client | + '[' -n multiconnect ']' client | + case "${TESTCASE}" in client | + '[' client == client ']' client | + /wait-for-it.sh sim:57832 -s -t 10 client | wait-for-it.sh: waiting 10 seconds for sim:57832 server | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 server | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin server | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin server | + '[' -n multiconnect ']' server | + '[' -n /logs/qlog/ ']' server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert server | ++ mktemp server | + P12CERT=/tmp/tmp.xBFQcjmN9p server | + mkdir -p /neqo/db server | + certutil -N -d sql:/neqo/db --empty-password server | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.xBFQcjmN9p server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.xBFQcjmN9p -W '' server | pk12util: PKCS12 IMPORT SUCCESSFUL server | + certutil -L -d sql:/neqo/db -n cert server | Certificate: server | Data: server | Version: 3 (0x2) server | Serial Number: server | 47:c3:82:19:ca:a1:97:d5:e4:35:a7:4c:c4:68:56:26: server | 6b:df:d6:31 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner Root Certificate Authority" server | Validity: server | Not Before: Wed Apr 30 11:27:24 2025 server | Not After : Sat May 10 11:27:24 2025 server | Subject: "O=interop runner leaf" server | Subject Public Key Info: server | Public Key Algorithm: X9.62 elliptic curve public key server | Args: server | 06:08:2a:86:48:ce:3d:03:01:07 server | EC Public Key: server | PublicValue: server | 04:e8:c6:42:d8:d6:fc:af:ca:3f:93:98:b2:e3:5e:af: server | 7a:d1:4b:ae:44:06:4b:84:f9:e3:f8:28:64:2f:c9:48: server | 3b:4a:3d:27:67:80:c9:1d:29:d7:bb:b1:41:09:2f:26: server | 20:ab:bb:5a:94:84:3f:7b:1c:4a:6a:32:44:c9:10:e6: server | 6d server | Curve: ANSI X9.62 elliptic curve prime256v1 (aka secp256r1, NIST P-256) server | Signed Extensions: server | Name: Certificate Subject Alt Name server | DNS name: "server" server | DNS name: "server4" server | DNS name: "server6" server | DNS name: "server46" server | server | Name: Certificate Subject Key ID server | Data: server | e2:66:6d:1f:50:82:ed:42:ea:7a:d4:97:18:5f:6e:ff: server | ba:1c:c6:33 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 6e:4f:3e:37:f4:42:cd:c1:a3:6a:a9:aa:3f:19:2b:2f: server | 15:44:17:48 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:46:02:21:00:f0:61:0a:ef:b4:eb:28:8e:2d:30:27: server | 8b:17:f6:89:c4:c4:84:2b:d3:f5:54:f9:ba:a6:89:94: server | d7:86:b3:5e:7a:02:21:00:e2:39:d7:3f:94:38:6a:6f: server | 43:ec:3b:c2:cb:f9:dc:61:79:c9:6f:7a:b6:42:e8:ea: server | b4:f9:55:30:af:87:ba:bd server | Fingerprint (SHA-256): server | 3D:95:7D:80:E0:F5:EA:19:9E:33:CE:3B:D5:E3:6D:1A:5A:84:84:19:1F:ED:BC:CA:61:D4:2D:4E:C8:70:AC:1F server | Fingerprint (SHA1): server | AE:1D:29:78:F0:E5:68:B7:56:AD:07:16:44:4A:60:4B:93:80:6F:A8 server | server | Mozilla-CA-Policy: false (attribute missing) server | Certificate Trust Flags: server | SSL Flags: server | User server | Email Flags: server | User server | Object Signing Flags: server | User server | server | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT") server | + '[' multiconnect = connectionmigration ']' server | + RUST_LOG=debug server | + RUST_BACKTRACE=1 server | + neqo-server --cc cubic --qns-test multiconnect --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443' server | ++ tee -i -a /logs/server.log server | 0.000 DEBUG Logging initialized server | 0.002 DEBUG Default socket send buffer size is 212992 server | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing server | 0.002 INFO Server waiting for connection on: [::]:443 server | 0.766 DEBUG [Server] Unsupported version: 57414954 server | 0.766 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:54361 IpTos(Cs0, NotEct) len 15 server | 0.766 DEBUG [Server] Unsupported version: 57414954 sim | server:443 is available after 1.020839766s server | 0.766 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:54361 IpTos(Cs0, NotEct) len 15 server | 0.766 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.766 DEBUG [Server] Unsupported version: 57414954 server | 0.766 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:54361 IpTos(Cs0, NotEct) len 15 server | 0.766 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) 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 | + echo 'Starting QUIC client...' client | Starting QUIC client... client | + '[' -n 'https://server4:443/lqozzoyqyu https://server4:443/xwvqxvyfvh https://server4:443/lmzqqpvqsn https://server4:443/xxzfwtwqjx https://server4:443/dwlypjxaek https://server4:443/vxtmdluioi https://server4:443/dtgfmdtmxa https://server4:443/cuymalyvth https://server4:443/wjbuoxifuu https://server4:443/ecashyjvix https://server4:443/ygdzkqqdxc https://server4:443/ijymnfcutd https://server4:443/ctamjbsgig https://server4:443/gorylarxet https://server4:443/qnzgkkurpp https://server4:443/sefjyistga https://server4:443/qtylywgeyd https://server4:443/ozndeigwpb https://server4:443/knsxehylbo https://server4:443/hludfcnbex https://server4:443/ussrwegdxc https://server4:443/ioijsvksem https://server4:443/pddfwvtssy https://server4:443/mdwvfqwmhx https://server4:443/qqpbthifbe https://server4:443/jjarhrywds https://server4:443/xabeusdgnv https://server4:443/epqlszbyau https://server4:443/mrryvjvuvp https://server4:443/lzukkmexqf https://server4:443/deemfozdkc https://server4:443/bejmhwshgu https://server4:443/bgdrprikah https://server4:443/epenlzhtjm https://server4:443/zulygxpurk https://server4:443/cmjbntkydh https://server4:443/wbctlummir https://server4:443/owcffcjsup https://server4:443/rbjbczpbzr https://server4:443/paevdrdmfu https://server4:443/wawxzfsbxa https://server4:443/ccfzbmixkf https://server4:443/oopyqgzisv https://server4:443/avdspknxqn https://server4:443/uvlssdfyge https://server4:443/ntrnzslixo https://server4:443/uqcviqptlw https://server4:443/inxputlmqi https://server4:443/qjerlkoxqp https://server4:443/zoxpqzfmss' ']' client | + REQS=("${REQUESTS}") client | + REQ='https://server4:443/lqozzoyqyu https://server4:443/xwvqxvyfvh https://server4:443/lmzqqpvqsn https://server4:443/xxzfwtwqjx https://server4:443/dwlypjxaek https://server4:443/vxtmdluioi https://server4:443/dtgfmdtmxa https://server4:443/cuymalyvth https://server4:443/wjbuoxifuu https://server4:443/ecashyjvix https://server4:443/ygdzkqqdxc https://server4:443/ijymnfcutd https://server4:443/ctamjbsgig https://server4:443/gorylarxet https://server4:443/qnzgkkurpp https://server4:443/sefjyistga https://server4:443/qtylywgeyd https://server4:443/ozndeigwpb https://server4:443/knsxehylbo https://server4:443/hludfcnbex https://server4:443/ussrwegdxc https://server4:443/ioijsvksem https://server4:443/pddfwvtssy https://server4:443/mdwvfqwmhx https://server4:443/qqpbthifbe https://server4:443/jjarhrywds https://server4:443/xabeusdgnv https://server4:443/epqlszbyau https://server4:443/mrryvjvuvp https://server4:443/lzukkmexqf https://server4:443/deemfozdkc https://server4:443/bejmhwshgu https://server4:443/bgdrprikah https://server4:443/epenlzhtjm https://server4:443/zulygxpurk https://server4:443/cmjbntkydh https://server4:443/wbctlummir https://server4:443/owcffcjsup https://server4:443/rbjbczpbzr https://server4:443/paevdrdmfu https://server4:443/wawxzfsbxa https://server4:443/ccfzbmixkf https://server4:443/oopyqgzisv https://server4:443/avdspknxqn https://server4:443/uvlssdfyge https://server4:443/ntrnzslixo https://server4:443/uqcviqptlw https://server4:443/inxputlmqi https://server4:443/qjerlkoxqp https://server4:443/zoxpqzfmss' client | ++ echo 'https://server4:443/lqozzoyqyu https://server4:443/xwvqxvyfvh https://server4:443/lmzqqpvqsn https://server4:443/xxzfwtwqjx https://server4:443/dwlypjxaek https://server4:443/vxtmdluioi https://server4:443/dtgfmdtmxa https://server4:443/cuymalyvth https://server4:443/wjbuoxifuu https://server4:443/ecashyjvix https://server4:443/ygdzkqqdxc https://server4:443/ijymnfcutd https://server4:443/ctamjbsgig https://server4:443/gorylarxet https://server4:443/qnzgkkurpp https://server4:443/sefjyistga https://server4:443/qtylywgeyd https://server4:443/ozndeigwpb https://server4:443/knsxehylbo https://server4:443/hludfcnbex https://server4:443/ussrwegdxc https://server4:443/ioijsvksem https://server4:443/pddfwvtssy https://server4:443/mdwvfqwmhx https://server4:443/qqpbthifbe https://server4:443/jjarhrywds https://server4:443/xabeusdgnv https://server4:443/epqlszbyau https://server4:443/mrryvjvuvp https://server4:443/lzukkmexqf https://server4:443/deemfozdkc https://server4:443/bejmhwshgu https://server4:443/bgdrprikah https://server4:443/epenlzhtjm https://server4:443/zulygxpurk https://server4:443/cmjbntkydh https://server4:443/wbctlummir https://server4:443/owcffcjsup https://server4:443/rbjbczpbzr https://server4:443/paevdrdmfu https://server4:443/wawxzfsbxa https://server4:443/ccfzbmixkf https://server4:443/oopyqgzisv https://server4:443/avdspknxqn https://server4:443/uvlssdfyge https://server4:443/ntrnzslixo https://server4:443/uqcviqptlw https://server4:443/inxputlmqi https://server4:443/qjerlkoxqp https://server4:443/zoxpqzfmss' client | ++ cut -d/ -f3 client | ++ cut -d: -f1 client | + SERVER=server4 client | + for INVOCATION in ${INVOCATIONS} client | + echo 'requesting files '\''/lqozzoyqyu,/xwvqxvyfvh,/lmzqqpvqsn,/xxzfwtwqjx,/dwlypjxaek,/vxtmdluioi,/dtgfmdtmxa,/cuymalyvth,/wjbuoxifuu,/ecashyjvix,/ygdzkqqdxc,/ijymnfcutd,/ctamjbsgig,/gorylarxet,/qnzgkkurpp,/sefjyistga,/qtylywgeyd,/ozndeigwpb,/knsxehylbo,/hludfcnbex,/ussrwegdxc,/ioijsvksem,/pddfwvtssy,/mdwvfqwmhx,/qqpbthifbe,/jjarhrywds,/xabeusdgnv,/epqlszbyau,/mrryvjvuvp,/lzukkmexqf,/deemfozdkc,/bejmhwshgu,/bgdrprikah,/epenlzhtjm,/zulygxpurk,/cmjbntkydh,/wbctlummir,/owcffcjsup,/rbjbczpbzr,/paevdrdmfu,/wawxzfsbxa,/ccfzbmixkf,/oopyqgzisv,/avdspknxqn,/uvlssdfyge,/ntrnzslixo,/uqcviqptlw,/inxputlmqi,/qjerlkoxqp,/zoxpqzfmss'\''' client | requesting files '/lqozzoyqyu,/xwvqxvyfvh,/lmzqqpvqsn,/xxzfwtwqjx,/dwlypjxaek,/vxtmdluioi,/dtgfmdtmxa,/cuymalyvth,/wjbuoxifuu,/ecashyjvix,/ygdzkqqdxc,/ijymnfcutd,/ctamjbsgig,/gorylarxet,/qnzgkkurpp,/sefjyistga,/qtylywgeyd,/ozndeigwpb,/knsxehylbo,/hludfcnbex,/ussrwegdxc,/ioijsvksem,/pddfwvtssy,/mdwvfqwmhx,/qqpbthifbe,/jjarhrywds,/xabeusdgnv,/epqlszbyau,/mrryvjvuvp,/lzukkmexqf,/deemfozdkc,/bejmhwshgu,/bgdrprikah,/epenlzhtjm,/zulygxpurk,/cmjbntkydh,/wbctlummir,/owcffcjsup,/rbjbczpbzr,/paevdrdmfu,/wawxzfsbxa,/ccfzbmixkf,/oopyqgzisv,/avdspknxqn,/uvlssdfyge,/ntrnzslixo,/uqcviqptlw,/inxputlmqi,/qjerlkoxqp,/zoxpqzfmss' client | + /proxygen/_build/proxygen/bin/hq --mode=client --host=server4 --port=443 --protocol=hq-interop --httpversion=0.9 --use_version=true --quic-version=1 --path=/lqozzoyqyu,/xwvqxvyfvh,/lmzqqpvqsn,/xxzfwtwqjx,/dwlypjxaek,/vxtmdluioi,/dtgfmdtmxa,/cuymalyvth,/wjbuoxifuu,/ecashyjvix,/ygdzkqqdxc,/ijymnfcutd,/ctamjbsgig,/gorylarxet,/qnzgkkurpp,/sefjyistga,/qtylywgeyd,/ozndeigwpb,/knsxehylbo,/hludfcnbex,/ussrwegdxc,/ioijsvksem,/pddfwvtssy,/mdwvfqwmhx,/qqpbthifbe,/jjarhrywds,/xabeusdgnv,/epqlszbyau,/mrryvjvuvp,/lzukkmexqf,/deemfozdkc,/bejmhwshgu,/bgdrprikah,/epenlzhtjm,/zulygxpurk,/cmjbntkydh,/wbctlummir,/owcffcjsup,/rbjbczpbzr,/paevdrdmfu,/wawxzfsbxa,/ccfzbmixkf,/oopyqgzisv,/avdspknxqn,/uvlssdfyge,/ntrnzslixo,/uqcviqptlw,/inxputlmqi,/qjerlkoxqp,/zoxpqzfmss --early_data=false --psk_file= --conn_flow_control=107374182 --stream_flow_control=107374182 --outdir=/downloads --logdir=/logs --qlogger_path=/logs --v=2 client | + tee /logs/client.log client | I20250430 11:27:26.215081 43 HQClient.cpp:47] HQClient connecting to 193.167.100.100:443 sim | Corrupting 1252 bytes 193.167.0.100:48006 -> 193.167.100.100:443 offset 46 0xed -> 0x68, corrupted 1/1 (100.0%) sim | Forwarding 1252 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 1/2 (50.0%) server | 1.094 DEBUG [Server] Handle initial server | 1.094 INFO AddressValidation: no token; accepting server | 1.094 INFO [Server] Accept connection CID [8]: e3cb817766c2bb96 server | 1.094 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.095 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.096 INFO [Server ...] Received valid Initial packet with scid CID [0]: dcid CID [8]: e3cb817766c2bb96 server | 1.096 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=e3cb817766c2bb96 server | 1.096 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.096 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.096 DEBUG [Server e3cb817766c2bb96] State change from Init -> WaitInitial server | 1.096 DEBUG [Server e3cb817766c2bb96] pn=6876950 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, NotEct) len 1252 server | -> RX Crypto { offset: 0, len: 310 } server | -> RX Padding { len: 901 } server | 1.096 DEBUG Read Ok(310) bytes server | 1.096 DEBUG 0-RTT: no token, no 0-RTT server | 1.096 DEBUG Read secret available for Handshake: SymKey [32]: e95baf8e8803e417bec380349beff422030a04b1dcf90b6e907bf6d65abdd5c0 server | 1.096 DEBUG Write secret available for Handshake: SymKey [32]: 75e1e175f4821c075ebac4e303b960870a8d416665097c477266151c1077d748 server | 1.096 DEBUG Writing transport parameters, msg=8 server | 1.096 DEBUG Read secret available for ApplicationData: SymKey [32]: 2986ee47b53daa9746c8367c4494f790f65786b324d38b85f6c709ca77c47bfa server | 1.096 DEBUG Write secret available for ApplicationData: SymKey [32]: 59cb19597b70d4ea1f0ca6a8b1e7fef2a24b36399f339084ab53471d00d4e0d7 server | 1.097 DEBUG [Agent 0x5577d92b11d0] state -> InProgress server | 1.097 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.097 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.097 DEBUG [Crypto] Handshake keys installed server | 1.097 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.097 DEBUG [Crypto] Application write key installed server | 1.097 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 616, tv_nsec: 282492351 } server | 1.097 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:48006] Make permanent server | 1.097 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:48006] set as primary path server | 1.097 DEBUG [Server e3cb817766c2bb96] State change from WaitInitial -> Handshaking server | 1.097 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.097 DEBUG CRYPTO for in offset=0, len=90 server | 1.097 DEBUG [Server e3cb817766c2bb96] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 139 server | TX -> Ack { largest_acknowledged: 6876950, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 1.097 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.097 DEBUG CRYPTO for hs offset=0, len=721 server | 1.097 DEBUG [Server e3cb817766c2bb96] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 900 server | TX -> Crypto { offset: 0, len: 721 } server | 1.097 DEBUG packet_sent this=0x5577d92b0b40, pn=0, ps=761 server | 1.097 DEBUG Building Short dcid Some(CID [0]: ) server | 1.097 DEBUG [Server e3cb817766c2bb96] pad Initial from 900 to PLPMTU 1232 server | 1.097 DEBUG packet_sent this=0x5577d92b0b40, pn=0, ps=471 server | 1.097 DEBUG ECN probing: sent 1 probes server | 1.097 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.097 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.097 DEBUG Building Short dcid Some(CID [0]: ) server | 1.097 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG [Server e3cb817766c2bb96] delay duration 297.098152ms server | 1.097 DEBUG Setting timeout of 297.098152ms server | 1.097 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.097 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.097 DEBUG Building Short dcid Some(CID [0]: ) server | 1.097 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG [Server e3cb817766c2bb96] delay duration 297.066483ms server | 1.097 DEBUG Setting timeout of 297.066483ms sim | Forwarding 1232 bytes 193.167.100.100:443 -> 193.167.0.100:48006, corrupted 0/1 (0.0%) client | I20250430 11:27:26.367251 43 CurlClient.cpp:187] Sending request for /lqozzoyqyu client | I20250430 11:27:26.367353 43 CurlClient.cpp:187] Sending request for /xwvqxvyfvh client | I20250430 11:27:26.367446 43 CurlClient.cpp:187] Sending request for /lmzqqpvqsn client | I20250430 11:27:26.367525 43 CurlClient.cpp:187] Sending request for /xxzfwtwqjx client | I20250430 11:27:26.367609 43 CurlClient.cpp:187] Sending request for /dwlypjxaek client | I20250430 11:27:26.367684 43 CurlClient.cpp:187] Sending request for /vxtmdluioi client | I20250430 11:27:26.367763 43 CurlClient.cpp:187] Sending request for /dtgfmdtmxa client | I20250430 11:27:26.367841 43 CurlClient.cpp:187] Sending request for /cuymalyvth client | I20250430 11:27:26.367915 43 CurlClient.cpp:187] Sending request for /wjbuoxifuu client | I20250430 11:27:26.367990 43 CurlClient.cpp:187] Sending request for /ecashyjvix client | I20250430 11:27:26.368067 43 CurlClient.cpp:187] Sending request for /ygdzkqqdxc client | I20250430 11:27:26.368145 43 CurlClient.cpp:187] Sending request for /ijymnfcutd client | I20250430 11:27:26.368227 43 CurlClient.cpp:187] Sending request for /ctamjbsgig client | I20250430 11:27:26.368306 43 CurlClient.cpp:187] Sending request for /gorylarxet client | I20250430 11:27:26.368379 43 CurlClient.cpp:187] Sending request for /qnzgkkurpp client | I20250430 11:27:26.368464 43 CurlClient.cpp:187] Sending request for /sefjyistga sim | Corrupting 83 bytes 193.167.0.100:48006 -> 193.167.100.100:443 offset 44 0x56 -> 0xb1, corrupted 2/3 (66.7%) sim | Corrupting 356 bytes 193.167.0.100:48006 -> 193.167.100.100:443 offset 34 0x73 -> 0xbb, corrupted 3/4 (75.0%) sim | Corrupting 85 bytes 193.167.0.100:48006 -> 193.167.100.100:443 offset 47 0x39 -> 0xbf, corrupted 4/5 (80.0%) sim | Forwarding 351 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 4/6 (66.7%) server | 1.262 DEBUG saving datagram of 351 bytes server | 1.262 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.262 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.262 DEBUG Building Short dcid Some(CID [0]: ) server | 1.262 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG [Server e3cb817766c2bb96] delay duration 132.383036ms server | 1.262 DEBUG Setting timeout of 132.383036ms server | 1.262 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.262 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.262 DEBUG Building Short dcid Some(CID [0]: ) server | 1.262 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG [Server e3cb817766c2bb96] delay duration 132.312123ms server | 1.262 DEBUG Setting timeout of 132.312123ms server | 1.395 DEBUG [LossRecovery] PTO timer fired for Initial server | 1.395 DEBUG [LossRecovery] PTO timer fired for Handshake server | 1.395 DEBUG [Server e3cb817766c2bb96] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 6876950, smallest: 6876950, ack_needed: true }] }) server | 1.395 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 616, tv_nsec: 282492351 } server | 1.395 DEBUG [Server e3cb817766c2bb96] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 1.395 INFO Lost crypto frame space=in offset=0 length=90 server | 1.395 DEBUG [Server e3cb817766c2bb96] Lost: EcnEct0 server | 1.395 DEBUG [Server e3cb817766c2bb96] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 721 }) server | 1.395 INFO Lost crypto frame space=hs offset=0 length=721 server | 1.395 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 1.395 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.395 DEBUG CRYPTO for in offset=0, len=90 server | 1.395 DEBUG [Server e3cb817766c2bb96] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 142 server | TX -> Ack { largest_acknowledged: 6876950, ack_delay: 37619, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 1.395 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.395 DEBUG CRYPTO for hs offset=0, len=721 server | 1.395 DEBUG [Server e3cb817766c2bb96] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 903 server | TX -> Crypto { offset: 0, len: 721 } server | 1.395 DEBUG packet_sent this=0x5577d92b0b40, pn=1, ps=761 server | 1.395 DEBUG Building Short dcid Some(CID [0]: ) server | 1.395 DEBUG [Server e3cb817766c2bb96] pad Initial from 903 to PLPMTU 1232 server | 1.395 DEBUG packet_sent this=0x5577d92b0b40, pn=1, ps=471 server | 1.395 DEBUG ECN probing: sent 2 probes server | 1.395 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.395 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.395 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.395 DEBUG Building Short dcid Some(CID [0]: ) server | 1.395 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.395 DEBUG [Server e3cb817766c2bb96] delay duration 599.85563ms server | 1.395 DEBUG Setting timeout of 599.85563ms server | 1.395 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.395 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.395 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.395 DEBUG Building Short dcid Some(CID [0]: ) server | 1.395 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.395 DEBUG [Server e3cb817766c2bb96] delay duration 599.825624ms server | 1.395 DEBUG Setting timeout of 599.825624ms sim | Forwarding 1232 bytes 193.167.100.100:443 -> 193.167.0.100:48006, corrupted 0/2 (0.0%) sim | Forwarding 42 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 4/7 (57.1%) server | 1.427 DEBUG [Server e3cb817766c2bb96] Drop packet number space in server | 1.427 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.427 INFO [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:48006] discarding a packet without an RTT estimate; guessing RTT=332.639633ms server | 1.427 DEBUG [Server e3cb817766c2bb96] pn=6876951 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, NotEct) len 42 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 2, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.427 DEBUG [Server e3cb817766c2bb96] Rx ACK space=hs, ranges=[0..=1] server | 1.427 DEBUG [LossRecovery] ACK for Handshake - largest_acked=1 server | 1.427 DEBUG on_packets_acked this=0x5577d92b0b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1522 server | 1.427 DEBUG Acked crypto frame space=hs offset=0 length=721 server | 1.427 DEBUG Acked crypto frame space=hs offset=0 length=721 server | 1.427 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:48006] Path validated Instant { tv_sec: 616, tv_nsec: 615131984 } server | 1.427 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.427 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.427 DEBUG Building Short dcid Some(CID [0]: ) server | 1.427 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.427 DEBUG [Server e3cb817766c2bb96] delay duration 1.063252623s server | 1.427 DEBUG Setting timeout of 1.063252623s server | 1.427 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.427 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.427 DEBUG Building Short dcid Some(CID [0]: ) server | 1.427 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.427 DEBUG [Server e3cb817766c2bb96] delay duration 1.063119544s server | 1.427 DEBUG Setting timeout of 1.063119544s sim | Corrupting 83 bytes 193.167.0.100:48006 -> 193.167.100.100:443 offset 24 0x8f -> 0x3d, corrupted 5/8 (62.5%) sim | Forwarding 351 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 5/9 (55.6%) server | 1.512 DEBUG saving datagram of 351 bytes server | 1.512 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.512 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.512 DEBUG Building Short dcid Some(CID [0]: ) server | 1.512 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.512 DEBUG [Server e3cb817766c2bb96] delay duration 977.630965ms server | 1.512 DEBUG Setting timeout of 977.630965ms server | 1.512 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.512 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 1.512 DEBUG Building Short dcid Some(CID [0]: ) server | 1.512 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.512 DEBUG [Server e3cb817766c2bb96] delay duration 977.57973ms server | 1.512 DEBUG Setting timeout of 977.57973ms sim | Corrupting 85 bytes 193.167.0.100:48006 -> 193.167.100.100:443 offset 19 0xd2 -> 0x3f, corrupted 6/10 (60.0%) sim | Forwarding 351 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 6/11 (54.5%) server | 2.003 DEBUG saving datagram of 351 bytes server | 2.003 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.003 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 2.003 DEBUG Building Short dcid Some(CID [0]: ) server | 2.003 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.003 DEBUG [Server e3cb817766c2bb96] delay duration 486.89006ms server | 2.003 DEBUG Setting timeout of 486.89006ms server | 2.003 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.003 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 2.003 DEBUG Building Short dcid Some(CID [0]: ) server | 2.003 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.003 DEBUG [Server e3cb817766c2bb96] delay duration 486.807355ms server | 2.003 DEBUG Setting timeout of 486.807355ms server | 2.491 DEBUG [LossRecovery] PTO timer fired for Handshake server | 2.491 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: Some(Handshake), probe: EnumSet(Handshake), paced: false } server | 2.491 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 2.491 DEBUG [Server e3cb817766c2bb96] pn=2 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 37 server | TX -> Ping server | 2.491 DEBUG packet_sent this=0x5577d92b0b40, pn=2, ps=39 server | 2.491 DEBUG Building Short dcid Some(CID [0]: ) server | 2.491 DEBUG ECN probing: sent 3 probes server | 2.491 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.491 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 2.491 DEBUG Building Short dcid Some(CID [0]: ) server | 2.491 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.491 DEBUG [Server e3cb817766c2bb96] delay duration 2.189711943s server | 2.491 DEBUG Setting timeout of 2.189711943s server | 2.491 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.491 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 2.491 DEBUG Building Short dcid Some(CID [0]: ) server | 2.491 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.491 DEBUG [Server e3cb817766c2bb96] delay duration 2.189678501s server | 2.491 DEBUG Setting timeout of 2.189678501s sim | Forwarding 39 bytes 193.167.100.100:443 -> 193.167.0.100:48006, corrupted 0/3 (0.0%) sim | Corrupting 45 bytes 193.167.0.100:48006 -> 193.167.100.100:443 offset 16 0x0 -> 0x85, corrupted 7/12 (58.3%) sim | Forwarding 351 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 7/13 (53.8%) server | 2.982 DEBUG saving datagram of 351 bytes server | 2.982 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.982 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 2.982 DEBUG Building Short dcid Some(CID [0]: ) server | 2.982 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.982 DEBUG [Server e3cb817766c2bb96] delay duration 1.698111226s server | 2.982 DEBUG Setting timeout of 1.698111226s server | 2.982 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.982 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 2.982 DEBUG Building Short dcid Some(CID [0]: ) server | 2.982 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.982 DEBUG [Server e3cb817766c2bb96] delay duration 1.698041495s server | 2.982 DEBUG Setting timeout of 1.698041495s server | 4.682 DEBUG [LossRecovery] PTO timer fired for Handshake server | 4.682 DEBUG [Server e3cb817766c2bb96] Lost: EcnEct0 server | 4.682 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: Some(Handshake), probe: EnumSet(Handshake), paced: false } server | 4.682 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 4.682 DEBUG [Server e3cb817766c2bb96] pn=3 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 37 server | TX -> Ping server | 4.683 DEBUG packet_sent this=0x5577d92b0b40, pn=3, ps=39 server | 4.683 DEBUG Building Short dcid Some(CID [0]: ) server | 4.683 DEBUG ECN probing: sent 4 probes server | 4.683 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.683 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 4.683 DEBUG Building Short dcid Some(CID [0]: ) server | 4.683 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.683 DEBUG [Server e3cb817766c2bb96] delay duration 4.379499677s server | 4.683 DEBUG Setting timeout of 4.379499677s server | 4.683 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.683 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 4.683 DEBUG Building Short dcid Some(CID [0]: ) server | 4.683 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.683 DEBUG [Server e3cb817766c2bb96] delay duration 4.379468499s server | 4.683 DEBUG Setting timeout of 4.379468499s sim | Forwarding 39 bytes 193.167.100.100:443 -> 193.167.0.100:48006, corrupted 0/4 (0.0%) sim | Forwarding 45 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 7/14 (50.0%) server | 4.944 DEBUG [Server e3cb817766c2bb96] pn=6876959 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, NotEct) len 45 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 28809, first_ack_range: 3, ack_ranges: [], ecn_count: None } sim | Forwarding 351 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 7/15 (46.7%) server | 4.944 DEBUG [Server e3cb817766c2bb96] Rx ACK space=hs, ranges=[0..=3] server | 4.944 DEBUG [LossRecovery] ACK for Handshake - largest_acked=3 server | 4.944 DEBUG on_packets_acked this=0x5577d92b0b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=78 server | 4.944 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:48006] Path validated Instant { tv_sec: 620, tv_nsec: 132461468 } server | 4.944 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.944 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 4.944 DEBUG Building Short dcid Some(CID [0]: ) server | 4.944 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.944 DEBUG [Server e3cb817766c2bb96] delay duration 662.7666ms server | 4.944 DEBUG Setting timeout of 662.7666ms server | 4.944 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.944 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 4.944 DEBUG Building Short dcid Some(CID [0]: ) server | 4.944 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.944 DEBUG [Server e3cb817766c2bb96] delay duration 662.623782ms server | 4.944 DEBUG Setting timeout of 662.623782ms server | 4.944 INFO not saving datagram of 351 bytes server | 4.944 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.944 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 4.944 DEBUG Building Short dcid Some(CID [0]: ) server | 4.944 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.944 DEBUG [Server e3cb817766c2bb96] delay duration 662.471156ms server | 4.944 DEBUG Setting timeout of 662.471156ms server | 4.944 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.944 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 4.944 DEBUG Building Short dcid Some(CID [0]: ) server | 4.944 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.944 DEBUG [Server e3cb817766c2bb96] delay duration 662.428666ms server | 4.944 DEBUG Setting timeout of 662.428666ms server | 5.608 DEBUG [LossRecovery] PTO timer fired for Handshake server | 5.608 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: Some(Handshake), probe: EnumSet(Handshake), paced: false } server | 5.608 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 5.608 DEBUG [Server e3cb817766c2bb96] pn=4 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 37 server | TX -> Ping server | 5.608 DEBUG packet_sent this=0x5577d92b0b40, pn=4, ps=39 server | 5.608 DEBUG Building Short dcid Some(CID [0]: ) server | 5.608 DEBUG ECN probing: sent 5 probes server | 5.608 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.608 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 5.608 DEBUG Building Short dcid Some(CID [0]: ) server | 5.608 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.608 DEBUG [Server e3cb817766c2bb96] delay duration 1.848366867s server | 5.608 DEBUG Setting timeout of 1.848366867s server | 5.608 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.608 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 5.608 DEBUG Building Short dcid Some(CID [0]: ) server | 5.608 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.608 DEBUG [Server e3cb817766c2bb96] delay duration 1.848330409s server | 5.608 DEBUG Setting timeout of 1.848330409s sim | Forwarding 39 bytes 193.167.100.100:443 -> 193.167.0.100:48006, corrupted 0/5 (0.0%) client | E20250430 11:27:31.367611 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.367652 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.377971 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.377995 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378000 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378005 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378010 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378014 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378019 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378024 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378029 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378033 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378038 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378043 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378047 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client | E20250430 11:27:31.378052 43 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms sim | Corrupting 68 bytes 193.167.0.100:48006 -> 193.167.100.100:443 offset 14 0xf9 -> 0x2b, corrupted 8/16 (50.0%) sim | Forwarding 228 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 8/17 (47.1%) server | 6.141 INFO not saving datagram of 228 bytes server | 6.141 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.141 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 6.141 DEBUG Building Short dcid Some(CID [0]: ) server | 6.141 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.141 DEBUG [Server e3cb817766c2bb96] delay duration 1.316088923s server | 6.141 DEBUG Setting timeout of 1.316088923s server | 6.141 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.141 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 6.141 DEBUG Building Short dcid Some(CID [0]: ) server | 6.141 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.141 DEBUG [Server e3cb817766c2bb96] delay duration 1.316013812s server | 6.141 DEBUG Setting timeout of 1.316013812s server | 7.458 DEBUG [LossRecovery] PTO timer fired for Handshake server | 7.458 DEBUG [Server e3cb817766c2bb96] Lost: EcnEct0 server | 7.458 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: Some(Handshake), probe: EnumSet(Handshake), paced: false } server | 7.458 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 7.458 DEBUG [Server e3cb817766c2bb96] pn=5 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 37 server | TX -> Ping server | 7.458 DEBUG packet_sent this=0x5577d92b0b40, pn=5, ps=39 server | 7.458 DEBUG Building Short dcid Some(CID [0]: ) server | 7.458 DEBUG ECN probing: sent 6 probes server | 7.458 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.458 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 7.458 DEBUG Building Short dcid Some(CID [0]: ) server | 7.458 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.458 DEBUG [Server e3cb817766c2bb96] delay duration 3.696926046s server | 7.458 DEBUG Setting timeout of 3.696926046s server | 7.458 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.458 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 7.458 DEBUG Building Short dcid Some(CID [0]: ) server | 7.458 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.458 DEBUG [Server e3cb817766c2bb96] delay duration 3.696888977s server | 7.458 DEBUG Setting timeout of 3.696888977s sim | Forwarding 39 bytes 193.167.100.100:443 -> 193.167.0.100:48006, corrupted 0/6 (0.0%) sim | Forwarding 45 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 8/18 (44.4%) sim | Forwarding 351 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 8/19 (42.1%) server | 10.052 DEBUG [Server e3cb817766c2bb96] pn=6876961 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, NotEct) len 45 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 320363, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 10.052 DEBUG [Server e3cb817766c2bb96] Rx ACK space=hs, ranges=[0..=5] server | 10.052 DEBUG [LossRecovery] ACK for Handshake - largest_acked=5 server | 10.052 DEBUG on_packets_acked this=0x5577d92b0b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=78 server | 10.052 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:48006] Path validated Instant { tv_sec: 625, tv_nsec: 240576112 } server | 10.052 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.052 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) sim | Forwarding 351 bytes 193.167.0.100:48006 -> 193.167.100.100:443, corrupted 8/20 (40.0%) server | 10.052 DEBUG Building Short dcid Some(CID [0]: ) server | 10.052 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.052 DEBUG [Server e3cb817766c2bb96] delay duration 762.973396ms server | 10.052 DEBUG Setting timeout of 762.973396ms server | 10.052 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.052 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 10.052 DEBUG Building Short dcid Some(CID [0]: ) server | 10.052 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.052 DEBUG [Server e3cb817766c2bb96] delay duration 762.804891ms server | 10.052 DEBUG Setting timeout of 762.804891ms server | 10.052 INFO not saving datagram of 351 bytes server | 10.052 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.052 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 10.052 DEBUG Building Short dcid Some(CID [0]: ) server | 10.052 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.052 DEBUG [Server e3cb817766c2bb96] delay duration 762.763644ms server | 10.052 DEBUG Setting timeout of 762.763644ms server | 10.052 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.052 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 10.052 DEBUG Building Short dcid Some(CID [0]: ) server | 10.052 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.052 DEBUG [Server e3cb817766c2bb96] delay duration 762.726355ms server | 10.052 DEBUG Setting timeout of 762.726355ms server | 10.053 INFO not saving datagram of 351 bytes server | 10.053 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.053 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 10.053 DEBUG Building Short dcid Some(CID [0]: ) server | 10.053 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.053 DEBUG [Server e3cb817766c2bb96] delay duration 762.44053ms server | 10.053 DEBUG Setting timeout of 762.44053ms server | 10.053 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.053 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 10.053 DEBUG Building Short dcid Some(CID [0]: ) server | 10.053 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.053 DEBUG [Server e3cb817766c2bb96] delay duration 762.40828ms server | 10.053 DEBUG Setting timeout of 762.40828ms server | 10.816 DEBUG [LossRecovery] PTO timer fired for Handshake server | 10.816 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: Some(Handshake), probe: EnumSet(Handshake), paced: false } server | 10.817 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 10.817 DEBUG [Server e3cb817766c2bb96] pn=6 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 37 server | TX -> Ping server | 10.817 DEBUG packet_sent this=0x5577d92b0b40, pn=6, ps=39 server | 10.817 DEBUG Building Short dcid Some(CID [0]: ) server | 10.817 DEBUG ECN probing: sent 7 probes server | 10.817 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.817 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 10.817 DEBUG Building Short dcid Some(CID [0]: ) server | 10.817 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.817 DEBUG [Server e3cb817766c2bb96] delay duration 6.713591378s server | 10.817 DEBUG Setting timeout of 6.713591378s server | 10.817 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.817 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 10.817 DEBUG Building Short dcid Some(CID [0]: ) server | 10.817 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 10.817 DEBUG [Server e3cb817766c2bb96] delay duration 6.713556543s server | 10.817 DEBUG Setting timeout of 6.713556543s sim | Forwarding 39 bytes 193.167.100.100:443 -> 193.167.0.100:48006, corrupted 0/7 (0.0%) server | 17.531 DEBUG [LossRecovery] PTO timer fired for Handshake server | 17.531 DEBUG [Server e3cb817766c2bb96] Lost: EcnEct0 server | 17.531 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: Some(Handshake), probe: EnumSet(Handshake), paced: false } server | 17.531 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 17.531 DEBUG [Server e3cb817766c2bb96] pn=7 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:48006 IpTos(Cs0, Ect0) len 37 server | TX -> Ping server | 17.531 DEBUG packet_sent this=0x5577d92b0b40, pn=7, ps=39 server | 17.531 DEBUG Building Short dcid Some(CID [0]: ) server | 17.531 DEBUG ECN probing: sent 8 probes server | 17.531 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 17.531 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 17.531 DEBUG Building Short dcid Some(CID [0]: ) server | 17.531 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 17.531 DEBUG [Server e3cb817766c2bb96] delay duration 13.427449055s server | 17.531 DEBUG Setting timeout of 13.427449055s server | 17.531 DEBUG [Server e3cb817766c2bb96] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 17.531 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: fe6a98fb913a4c53d204) server | 17.531 DEBUG Building Short dcid Some(CID [0]: ) server | 17.531 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 17.531 DEBUG [Server e3cb817766c2bb96] delay duration 13.427414481s server | 17.531 DEBUG Setting timeout of 13.427414481s sim | Forwarding 39 bytes 193.167.100.100:443 -> 193.167.0.100:48006, corrupted 0/8 (0.0%) client | I20250430 11:27:43.109985 43 H1QUpstreamSession.h:109] onConnectionError client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 11:27:53,536 Using the server's key log file. 2025-04-30 11:27:53,545 2025-04-30 11:27:53,545 Using the server's key log file. 2025-04-30 11:27:53,546 Using the server's key log file. 2025-04-30 11:27:53,553 2025-04-30 11:27:53,553 Using the server's key log file. 2025-04-30 11:27:53,841 Expected 50 handshakes. Got: 1