2025-04-30 11:25:46,025 Generated random file: nkvzprlxxx of size: 5120
2025-04-30 11:25:46,025 Requests: https://server4:443/nkvzprlxxx
2025-04-30 11:25:46,411 
2025-04-30 11:25:46,412 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_7ygsg9gw/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_f_ki57ol/ DOWNLOADS=/tmp/download_64xtc995/ SERVER_LOGS=/tmp/logs_server_0qu23dq9 CLIENT_LOGS=/tmp/logs_client_c1cv_ljz SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=ghcr.io/facebook/proxygen/mvfst-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/nkvzprlxxx"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 11:26:46,444  Container sim  Recreate
 Container sim  Recreated
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
server  | Setting up routes...
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | + 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...
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 transfer ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
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  | + P12CERT=/tmp/tmp.9k1ocbObOK
server  | + mkdir -p /neqo/db
client  | Endpoint's IPv4 address is 193.167.0.100
server  | + certutil -N -d sql:/neqo/db --empty-password
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/nkvzprlxxx
client  | ++ tr ' ' '\n'
client  | ++ awk -F / '{ print "/" $4 }'
client  | ++ paste -sd,
client  | + INVOCATIONS=/nkvzprlxxx
client  | + EARLYDATA=false
client  | + PSK_FILE=
client  | + '[' -n transfer ']'
client  | + case "${TESTCASE}" in
client  | + STREAM_FLOW_CONTROL=262144
client  | + CONN_FLOW_CONTROL=2621440
client  | + '[' client == client ']'
client  | + /wait-for-it.sh sim:57832 -s -t 10
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.9k1ocbObOK
server  | Warning: output encryption option -nodes ignored with -export
client  | wait-for-it.sh: waiting 10 seconds for sim:57832
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.9k1ocbObOK -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  |             51:c4:bd:76:c7:31:8f:0f:43:14:b7:57:98:b6:31:36:
server  |             53:df:f5:cc
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner intermediate 8"
server  |         Validity:
server  |             Not Before: Wed Apr 30 11:25:46 2025
server  |             Not After : Sat May 10 11:25:46 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:70:35:17:6e:0b:0b:3b:58:49:32:fe:9e:fd:2c:7e:
server  |                     df:10:69:47:9a:3a:57:ec:46:64:35:76:3d:e4:c1:84:
server  |                     28:61:65:24:69:c9:91:da:02:32:ff:2e:2f:fb:43:4c:
server  |                     ac:e3:e4:a9:2b:f1:0a:60:1e:ed:00:80:c3:76:fe:da:
server  |                     52
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  |             DNS name: "y8aVeHVe6Kk9CjiNN3my43BIk77cnMIiVGZromLgAXcAP9HH9a9JlT
server  |                 qVYUpriGyeno372EyOfvGp35GFDPdt3CP7yPZsEYgjYKROXmpRsS6ozu6fekK
server  |                 jHndiiZhctrRz86hlOavNR0VHEdbpbtKj82Dq7IZOnoCwTe0NCvXzx1Xbtzzr
server  |                 JDIz7Qdvvt2zZlHBxkMzx5JioQzh3aRJdJLp4v2o9zRgiRDpKXhLlShhTIVTx
server  |                 Mn6tXDebUVGxw"
server  |             DNS name: "eArvaOb3QDiwPgUoiSgkJCpSQ7lfyBedFtB3tRxK3hRB0RH9FWeaGH
server  |                 my97X9HVcoTqNjHSkkJfWpbcym17k6Dh5HTonfNWYURrAELz9EbqWo1rP4LiB
server  |                 42aeVhA7PD3Wsur2gDneEwSmG2onBwyIQcbh5gbGyCZsNyGtaK8t1lpUSeiws
server  |                 QAhvafAqoc3r0rkq95UrH7eKcjqSkX83OTD2VP6OsLe7KuY4jjOmczRuAUHEr
server  |                 Ut97E0n6fOc29"
server  |             DNS name: "uRw8AYJyqKJstHNbsLOFCUA9uC7u9tRJ2sWKhONLNHGumFg1gWAWQ8
server  |                 kBOicsymtQHdBjSJ3tmGAcV4gCKHfTop4FYMKwkJwY4gdJrP2AgUtePcXt125
server  |                 J9JtP1EwJ0gxGz0TQwmT79ZmwFGcTX0n7jEeWrw92AUyaJyocfh9HZt5aLZPb
server  |                 zrzHq8Zh14yvp4vtQL2vsEvKXvfxewsPF1HcCWRD0qyzlze5v5HQrwhLDv3TE
server  |                 QvzrAjrQPuIZK"
server  |             DNS name: "IDo0RI2FGzfofe40ZwrbtwayUMioTrsKbYcFlI23YpLKGljrhixuoq
server  |                 1Jnqk1bBhJzt66eZTvDcxmnWeV6itUGLSMkvq5i5S9PQ5HD24OwllEliEIOEg
server  |                 k2lClIlUCbOFhvH9wc60Vh5gGv7RE9xvUl9fwArTlDzdCv39jZtk2zX4jNgDd
server  |                 pfkgXkSVPiHNwdSf8OVVvm5zVTRbDZcOxNIliBXYTQLhPq82RaQowtY7obaTR
server  |                 3bkhuYfoKoJtj"
server  |             DNS name: "qIRCfhRHJSaapae3GSAZW3QCOehf5zFo6XBKcbDvQ2B2fyALoIWOrv
server  |                 QEjudFd1baZv1u7NETNm29pkBvkCUmibpiWcYHcVNM4DJhDO9GzMG5xsKiFyO
server  |                 4MWUBA5IFvG9myYQEVXMNjNImLc4FnaUOW69euU3TF7EsqWFG0v5YwLCygWNY
server  |                 EPJKqIZ0EwDhHBf5shhCuiGUbHbb3kYXEQZHvbuK448AuJDsv28rxqPuwA8dU
server  |                 LAFm5ShHy0y29"
server  |             DNS name: "mEjEeBwLHtCTLXpARhB7nzuVxKhyudCCx0Fle9iJMInj5UhVzewxpt
server  |                 FIYTR0GtDrxVrxiBsTZg5Z5qg70XQ6pvPdZGQ7uMN0tZJ1Nro7Ezy5qCTIdkh
server  |                 r5AUu2YKgcUFHBMFzeCuPmZ8eZ9SxNMoGOQkliNYHdqoFqZA28EkwdXDfw4Rr
server  |                 xTTCRiEkwwQklJyvNpiP7WHp34cLxP1dzQsTCpX8LH5x6uSwSd1bVVtOD4beD
server  |                 i8P01Fsfr2Om3"
server  |             DNS name: "MkAiLMVBWZNkOAHZScziZWNo8esorsMD0EKhgBYjQPNf2HMVLBgcKC
server  |                 LCLNb0jAfv0svJhViHopcJ0Yq6EY55qshosqiSmhYkobNNUrTRUEELF9F8j88
server  |                 MpNwL625DzL5ilgPGwHYQb54eZZTMNidq9fE9ocQVoFfyfFgVTeOnsjDR2eMU
server  |                 7lbjYenh1Uluk8uaNHMtmDwPFU5PJlka9upp95gghUHyRRizt7b8JK9HWhbca
server  |                 gwACO4zpBhyMT"
server  |             DNS name: "esI7a2LgRNzaSVq9nmlTU1Hsgd1G8GEzqaE8Hf8AFoQTk39KfC94lA
server  |                 U1BZAtZB8zmJqexSUUVpsvyGRjdeseyScGVmBbv3h6c1CwRBBjKrf0dUQA50q
server  |                 mfPxrc39nWIk2352Jxoc3F1cxk0PsyLzNo16Gtg3TQIvCzuoFKiKzUPGd7uAO
server  |                 K0WMYoYxaSgsefNc7TBlTWo6O0UxBGbZ1lFA8J3nCq8MgehqcsZ0FsBipZI10
server  |                 JInalGSPT2yqS"
server  |             DNS name: "0PbVPkiC1qLOvFNjKI5fA8fm7JsocTDWw9HGz6zZ3UabLgApfvkijt
server  |                 9o6dbtiIKsDzdt97S4YmQbHvkY3D3vZFUdpilFCod5piz1WpJeFAkPh0wPjZx
server  |                 xKnthWgIrEFiTRfV69x1unhuEEFEmZwxpVWsQUO8cWrJmRzhwo5pVp6k4b4ra
server  |                 QmCjGXDAjpu1FD8BEZBhpDv5URBquNhaWQhsXEqD7KNNkFAJIL4rd1k2k6Std
server  |                 827K0wuwAH1j9"
server  |             DNS name: "AHqY33kUMgMIQPeURc0w4bxSWCu1cLPvegFlTygufzBe0BPXdSwDjR
server  |                 Ol0BWugXicE6xs8gapwBDfSPGaepGAbGOlY9iU4hpem73MCIZ9pgjY9O7WS90
server  |                 2TOIpI7CrLqGMtirSL78oCaGLnKN1TAtkaDMB8QmK66TkUyeUkJMjrHGbxyMs
server  |                 nQRXVBeE6Z3ZSVwEj93lucB9e8O6auDeoHLPWWMIctHSbdSZrgQovRh786WFp
server  |                 AgHG0Jf5GYOvO"
server  |             DNS name: "VfJW3BnSfzxY4E6qPZsjZpwnq8Hfww6n3VPX9U6upyDm6DPL7pHvrE
server  |                 YH3EBlMvvTwtO4Yrd5T3TQ4d8Vtlm7YLKshBTLHQlUmCwIyGkk3Ms7BwoYZzY
server  |                 IG4cXBX3xtU1dWzroaC4YJE6EL6HWhkXzkzervJJdu75zftHaSe0kaQFRkxUs
server  |                 fuv5dGpmx71Htxe0mbOgYYCIh23GOt8cD79HiXAtTUNzD6b8a9fgx7s3bnA4h
server  |                 MGwzULdZKoPyG"
server  |             DNS name: "9Lpw9yJFUQ38INH6YLnv1YHoUxwnLkYp2ll4QQo1Lz5moYLKPWZdXs
server  |                 cnS8M1oC5o5WdjpAJi7OQMSbchSO9WpMyu81LncO3GG7zAsw9XEkFt8gRaMLX
server  |                 dtqPeMP9FHOFLTx3A0dea32rshDru7bueSGZAeWKa40hbuTZECH3QBR4CIjLe
server  |                 4cgJ1u2ECRXQFUCX0wIPfvKHA0eq2epa7GgK1TrWraB9yeWbwAxGlX144vtFX
server  |                 G15zoJKxyN27L"
server  |             DNS name: "SUiLYB7269xgQefBqvUtMg4nmGMfvaXzQWybHIq2iNYTEnocMwbPkm
server  |                 e3348LXlilGvFpQ3VIdKq5SjNtf62bN28sqlmUveIX2edGolrquxHytf3ma1V
server  |                 zcjUEUJ4dMJoCCssmgmAFZ4rBeFQBJlkvkwofxTinzHRZaeWOVMcMnMBOlWlk
server  |                 NoEY5OB5jiaalJlyRoXLLukQXTL9y1PkUh7xkzW5GgJPpCGRIOeEo4Uw0lhnk
server  |                 l1f0rKMWDhnlE"
server  |             DNS name: "MLsM0djAyYqucmghOFwcvUX7vnRo9ncjyTh2CfZ7LLVGfRAJsPEU1u
server  |                 PnXAyod6qMDYqMSZJdTZlIZzPL68jxu6QYQTCJH4Ilvi2imu09tFnMI651qH5
server  |                 XVBd2w75syrT3nBpfrOnXVU09JSGAiFHDGhn9r2GgdFHz1PVYuNW9psRjK6q2
server  |                 eqeamylurcWqKKgvFTKeOF4p44VYndgKH3Q3buBjLXnDjVQv8YPWKNVENIANu
server  |                 tmzB0xNM9XhHd"
server  |             DNS name: "FjwrJak2y9dcUaaNcLQMWzNcLR7ESlf88R0Qxs6KIy88Xw1hUwAq4O
server  |                 0mc4gL3ZVWwucDJ37phaCjkkXOwDg3sDMIUzISGt08UA2XV2WFEsbIs9DscQC
server  |                 ADiEZ3znIlGGUoeq4riAwjFuSCqkFcLEcYTCt3xXekCB9iYtiZOAXCIS6aSSU
server  |                 SLbsGUns2Xg0qoEVbgSatBHcjHCkDxeZsZMbuf2y4tfJHIDTWAEJthK6b9qkK
server  |                 aRv8SLdvJAVAX"
server  |             DNS name: "p4RnKeAKmiQJVxXGlUemqKK0E1RoLNooJZ3bXCj4VChpT8sTPWy3iC
server  |                 fHlI4YGeZWac2RiQKtDtyPTEIj3qIimPlmCn0ShpPpSn9unN9GQYshyUrrE0A
server  |                 EhOzWVKOPpeoE6iGmhIZTZkUVXqM4Uw8p0Y59flObpqk8DkbyMhbDGs8JTjhm
server  |                 qyX186bKVFY1CMWXTS2HhJFDgRnASFeS4SGK86807UmRaAJtt7veYwipKqxmi
server  |                 QW1TaSSpYsMF0"
server  |             DNS name: "athJHrNkTeoq7S8GpymQwxN2IrR5pvTsEGZLu40UmLFnH0Mbw2jd3S
server  |                 n3d4n8um2OBRFonuQ7DI497riVjYHwQMJpNKutvIuHX7CSAe8s4XHP9aHYy5E
server  |                 KBJBWDbJhH5hJyQ6Gob0kycyUiRiA9DlLpPkbPzI9afL6mYHGvuZdwicMxTRt
server  |                 H2XtvFHJOC4jGfZWuEaDbUgNsndpLqxtE7ikJNCHbBIRgHarhKVsQpav94TKR
server  |                 NwiH6P0gIqD8n"
server  |             DNS name: "Ke3N1FyvMZUHgn9A52QBADtMkilytEXrGT7DvvrC7sjZr8UBhtwb9v
server  |                 9Kxb73CyN0a45yZxocjjKGuvxKPSAhXAiDmqY2AtJ3Inuk4PkMYIW0Q54VDfC
server  |                 zxcE0EX4x0VtabPWBnNJ2tfOjoNexwyV26ec4IwCISdEd4DDHajfnMAcaD0xQ
server  |                 msJBaYN2kIzZ06pXvtDAheV9UsuwAa5UXrGS4bzylxcNOpNN4cn7nSNyRqGmP
server  |                 VUQONr9JMkm6a"
server  |             DNS name: "UnUbfHEvQPO64BqNEwsh5WUUBQyBGbL6GlZRWf3ott5pRZC56acv0Z
server  |                 bLYXsZI5DpFm9nOiAsvjORxyB73mAmBfXArJVbYuC2jbk7lFBeldknLVCEO8N
server  |                 YHN6zyCqn90zHwYeu0Xt9G68k6UnrLpwv15VOtrVbfjTU7OhXTiVZl4MTjrg8
server  |                 kuyQqsPcMWpO1FMgBYPueUiiSP0pCVk1DMTq8LfqxWYpeNPxb7U0E5kM7xlkK
server  |                 cSXTD7O3Lnzyc"
server  |             DNS name: "GyNOzQK3GGnBShKQ0TQsEWJdvgxXjgvtNij55cso3zFX5H88K2MyEW
server  |                 ceJlW4nkg0C5WreKJODawWnHOed2cx27iwQOrHRyuwOhi6xXD0ubui37vLCx9
server  |                 iWFpyqevADY24xcga0nbYuxi0zv9qSX3rJJsApsq1Cw4L1KNK2YdGFT5XVDFG
server  |                 sD8FfquMIolJAOcfHmgWHtN7zTo1WXdp36NsQicRvJQiiKOFqSLjqy45AAcan
server  |                 CZl2bG9bNMeg8"
server  | 
server  |             Name: Certificate Subject Key ID
server  |             Data:
server  |                 01:93:99:5e:7e:8b:ca:63:10:fb:6b:4c:86:b8:af:bd:
server  |                 1f:3d:a0:d1
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 3d:ae:d4:7c:3f:c6:a8:19:04:88:80:e4:8c:c3:52:ac:
server  |                 99:9b:ac:20
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:44:02:20:19:82:47:02:f1:11:02:58:e4:4d:8d:20:
server  |         22:fa:82:ee:84:09:5b:6f:2e:9e:0c:ae:38:09:2c:01:
server  |         ad:95:f7:14:02:20:19:6c:bd:14:3e:ec:25:74:77:25:
server  |         11:33:58:d1:32:44:9d:58:61:90:60:33:0f:2e:e0:d8:
server  |         9a:80:41:8b:dc:16
server  |     Fingerprint (SHA-256):
server  |         76:0C:2B:D5:C3:48:C1:FC:97:96:10:94:4C:1F:7F:64:EF:95:93:01:53:F7:08:DA:52:A6:D5:5F:BD:BF:F5:D6
server  |     Fingerprint (SHA1):
server  |         64:B5:B6:05:98:A3:71:24:C6:1E:2F:4F:56:4E:47:D5:60:96:42:B7
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  | + '[' transfer = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test transfer --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.734 DEBUG [Server] Unsupported version: 57414954
server  | 0.734 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46913 IpTos(Cs0, NotEct) len 15
server  | 0.734 DEBUG [Server] Unsupported version: 57414954
server  | 0.734 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46913 IpTos(Cs0, NotEct) len 15
server  | 0.734 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | server:443 is available after 1.025139449s
server  | 0.734 DEBUG [Server] Unsupported version: 57414954
server  | 0.734 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46913 IpTos(Cs0, NotEct) len 15
server  | 0.734 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 2 seconds
client  | + echo 'Starting QUIC client...'
client  | Starting QUIC client...
client  | + '[' -n https://server4:443/nkvzprlxxx ']'
client  | + REQS=("${REQUESTS}")
client  | + REQ=https://server4:443/nkvzprlxxx
client  | ++ echo https://server4:443/nkvzprlxxx
client  | ++ cut -d/ -f3
client  | ++ cut -d: -f1
client  | + SERVER=server4
client  | + for INVOCATION in ${INVOCATIONS}
client  | + echo 'requesting files '\''/nkvzprlxxx'\'''
client  | requesting files '/nkvzprlxxx'
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=/nkvzprlxxx --early_data=false --psk_file= --conn_flow_control=2621440 --stream_flow_control=262144 --outdir=/downloads --logdir=/logs --qlogger_path=/logs --v=2
client  | + tee /logs/client.log
client  | I20250430 11:25:48.011317    43 HQClient.cpp:47] HQClient connecting to 193.167.100.100:443
server  | 0.989 DEBUG [Server] Handle initial
server  | 0.989 INFO AddressValidation: no token; accepting
server  | 0.989 INFO [Server] Accept connection CID [8]: 7eec6e95370a489d
server  | 0.989 DEBUG Overwrite initial version Version1 ==> Version1
server  | 0.993 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 0.993 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [8]: 7eec6e95370a489d
server  | 0.993 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=7eec6e95370a489d
server  | 0.993 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.993 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.993 DEBUG [Server 7eec6e95370a489d] State change from Init -> WaitInitial
server  | 0.993 DEBUG [Server 7eec6e95370a489d] pn=4297969 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:36359 IpTos(Cs0, NotEct) len 1252
server  |   -> RX Crypto { offset: 0, len: 310 }
server  |   -> RX Padding { len: 901 }
server  | 0.993 DEBUG Read Ok(310) bytes
server  | 0.993 DEBUG 0-RTT: no token, no 0-RTT
server  | 0.993 DEBUG Read secret available for Handshake: SymKey [32]: 401941deb156a91f1ec46bdd25e450da88e6047e175613fbf886db81bb6b8e05
server  | 0.993 DEBUG Write secret available for Handshake: SymKey [32]: c54bf4075b5232c61bd394369bc2c554711d67771f5ac7d3e6ff10c069b83615
server  | 0.993 DEBUG Writing transport parameters, msg=8
server  | 0.994 DEBUG Read secret available for ApplicationData: SymKey [32]: 104be3c8ffee5a5a682b712d7a60f358b0154614a249e300f6c1103ec787c5d0
server  | 0.994 DEBUG Write secret available for ApplicationData: SymKey [32]: 6d7693088a9fac2382d39c4b2afc04f3ef9e3f4696f7792e19d0cae883cdc448
server  | 0.994 DEBUG [Agent 0x557eddb5b1d0] state -> InProgress
server  | 0.994 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.994 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.994 DEBUG [Crypto] Handshake keys installed
server  | 0.994 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 0.994 DEBUG [Crypto] Application write key installed
server  | 0.994 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 517, tv_nsec: 969311998 }
server  | 0.994 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:36359] Make permanent
server  | 0.994 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:36359] set as primary path
server  | 0.994 DEBUG [Server 7eec6e95370a489d] State change from WaitInitial -> Handshaking
server  | 0.994 DEBUG [Server 7eec6e95370a489d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.994 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG CRYPTO for in offset=0, len=90
server  | 0.994 DEBUG [Server 7eec6e95370a489d] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:36359 IpTos(Cs0, Ect0) len 139
server  |   TX -> Ack { largest_acknowledged: 4297969, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 0.994 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG CRYPTO for hs offset=0, len=1053
server  | 0.994 DEBUG [Server 7eec6e95370a489d] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:36359 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 0, len: 1053 }
server  | 0.994 DEBUG packet_sent this=0x557eddb5ab40, pn=0, ps=1093
server  | 0.994 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.994 DEBUG packet_sent this=0x557eddb5ab40, pn=0, ps=139
server  | 0.994 DEBUG ECN probing: sent 1 probes
server  | 0.994 DEBUG [Server 7eec6e95370a489d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.994 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG CRYPTO for hs offset=1053, len=1191
server  | 0.994 DEBUG [Server 7eec6e95370a489d] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:36359 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1053, len: 1191 }
server  | 0.994 DEBUG packet_sent this=0x557eddb5ab40, pn=1, ps=1232
server  | 0.994 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.994 DEBUG ECN probing: sent 2 probes
server  | 0.994 DEBUG [Server 7eec6e95370a489d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.994 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG CRYPTO for hs offset=2244, len=1191
server  | 0.994 DEBUG [Server 7eec6e95370a489d] pn=2 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:36359 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 2244, len: 1191 }
server  | 0.994 DEBUG packet_sent this=0x557eddb5ab40, pn=2, ps=1232
server  | 0.994 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.994 DEBUG ECN probing: sent 3 probes
server  | 0.994 DEBUG [Server 7eec6e95370a489d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.994 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.994 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.994 DEBUG [Server 7eec6e95370a489d] delay duration 29.995091256s
server  | 0.994 DEBUG Setting timeout of 29.995091256s
server  | 0.994 DEBUG [Server 7eec6e95370a489d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.994 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f1eb7a397eb213366642)
server  | 0.994 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.994 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.994 DEBUG [Server 7eec6e95370a489d] delay duration 29.99506151s
server  | 0.994 DEBUG Setting timeout of 29.99506151s
sim     | Dropping packet 2 (42 bytes) from 193.167.0.100
sim     | Dropping packet 3 (42 bytes) from 193.167.0.100
sim     | Dropping packet 4 (42 bytes) from 193.167.0.100
server  | 30.990 INFO [Server 7eec6e95370a489d] idle timeout expired
server  | 30.990 DEBUG [Server 7eec6e95370a489d] State change from Handshaking -> Closed(Transport(IdleTimeout))

2025-04-30 11:26:46,444 Test failed: took longer than 60s.
2025-04-30 11:26:46,495 time="2025-04-30T11:26:46Z" level=warning msg="The \"SERVER\" variable is not set. Defaulting to a blank string."
time="2025-04-30T11:26:46Z" level=warning msg="The \"CLIENT\" variable is not set. Defaulting to a blank string."
invalid spec: :/www:ro: empty section between colons