2025-04-30 11:49:42,475 Generated random file: ojshxnianm of size: 5120 2025-04-30 11:49:42,475 Requests: https://server4:443/ojshxnianm 2025-04-30 11:49:42,863 2025-04-30 11:49:42,864 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_slq_ana2/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_73z5yn6l/ DOWNLOADS=/tmp/download_3pgniqk4/ SERVER_LOGS=/tmp/logs_server_86a_43mb CLIENT_LOGS=/tmp/logs_client_yqvj_wgk SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=ghcr.io/microsoft/msquic/qns:main SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/ojshxnianm" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 11:50:05,007 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 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 transfer ']' server | + '[' -n /logs/qlog/ ']' server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert server | ++ mktemp server | + P12CERT=/tmp/tmp.jPPidhRXy2 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.jPPidhRXy2 client | Setting up routes... client | Actual changes: client | tx-checksumming: off client | tx-checksum-ip-generic: off client | tx-checksum-sctp: off client | tcp-segmentation-offload: off client | tx-tcp-segmentation: off [requested on] client | tx-tcp-ecn-segmentation: off [requested on] client | tx-tcp-mangleid-segmentation: off [requested on] client | tx-tcp6-segmentation: off [requested on] client | tx-udp-segmentation: off [requested on] server | Warning: output encryption option -nodes ignored with -export client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | + pk12util -d sql:/neqo/db -i /tmp/tmp.jPPidhRXy2 -W '' client | wait-for-it.sh: waiting 30 seconds for sim:57832 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 | 16:65:75:08:99:d5:d3:82:bd:85:50:09:d3:aa:c9:3a: server | 28:9a:bf:a1 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:49:42 2025 server | Not After : Sat May 10 11:49:42 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:b8:ec:1a:2d:81:b1:6d:e6:15:97:49:52:72:8e:fa: server | e6:a4:96:00:87:26:4e:6d:94:76:63:85:ac:e8:5e:f5: server | 07:11:75:31:8d:f8:5b:28:7f:bf:79:dc:f2:7d:66:67: server | 66:3a:a7:8a:f0:77:71:8d:7d:85:37:ac:45:d5:de:b4: server | 76 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: "dtJRIPkkXwyDQANGtbpZZeTtA3tdmR8l3pZs4TjOHM56CpJhsWSnBt server | k6Mi8coPdiZYAWn1TCbvXeudtStHpghNWGexA8uoGEYmQ71hc6Y6II5PzPN0P server | YnO4gBsNWawlmnSuA8DztpHjxliPgbUBW5Sm43pgLd9X9zzmLJEb5jUNlfUoS server | nz2Q0OG6TTT6tubODg7frJP6la74ZG8pUxfFVk3luw9d6KEEo8aouwCPwfiZH server | 1uREHEw7hkpPA" server | DNS name: "RmEV0Ej4srsNgQ49uEVFDqDg8uUTCScchZ6xynRtXsft39AYFHnApe server | SCyKRwdMygHYOgAyzO8JAhp0x7KggLFZGeqL3l1cF3pQHAvNgxCYQuKMVMQed server | WY1sQs22vE8ydAGLviI7Rdp5ETb1yy8GTZ1nFvbSijslu7a5XEEZ6qemQDzMD server | iiXSpSrK0iZ3iqj1vGPVSlVJbgFcJ6Zqbd7CHbAoxpkogORnirv9ZSbGKknOn server | BGQdQlcq7U7in" server | DNS name: "tbCFcTHr15gEfUafseGv4XmBmfshph2ecfVhqONfOnwWghpOCk1XUB server | fh4ZIWrg0fGXCNLUPrVpgBENOcDMYW46ls9wPffQO1vBelTKv4OYeWjmTYbvT server | MsR4Ot4iTToPW8jUwiqXW6sBxQHLKk8HP5huZrg8WVPcNZrpllM9H5JHVWWsV server | 1DtuBSWqsstwWjLym8QnJ5QlGE0SouTBxid8eI9ZRc74Nr3kZNOmnTkuNlCga server | sbV2Gjnlu9TdQ" server | DNS name: "jaAeZXWS5cAyXqrOGLyPDfP7mH78NX6b9VhILNOXxFSiKINQAUksfr server | kNaozOtaccl1mB5f6yhKbiFaLDxdIWVyhDFqQu6s0W2b9LbWYB33I6RydmhU5 server | er5r1s1HKSPo5BGAmLeKtXL39CgGRjfeDvwkje3RVgUzfZQz9Emp7J9sLPTg7 server | ryrlFKAgPRvVE6qvhuFZ32bWUJYSleHiYFeTOK5gCq9oRLacouoiDNkXxkPx5 server | 5xenWskpWNDVI" server | DNS name: "31UOzDOEVAFap63CRXUiPuMVsygOJXrhhmnc0Uk36JJGO6D62rQSwa server | 7yWW1JJugwlxGIgUVG1zKfhmMXrAKBOqQoAqnHQQDYYk6ks6jQjhnQnVdp4sb server | WTgPfTt0lU1VQRZ3WmHbp49OWpnW2pUEhE06ayhJNYsg1fHnyAA5Pu6cKjKk7 server | opkRstlWWUGUVSqqzDQQHuYlGJaqjxmqvVapgvvW6ofGFudsgbWBQ3UvNVzIN server | DfzcUlD7tTZvn" server | DNS name: "7AoMCYnCGDFJjBLfFL5bwvRE3nMb7SkVhZWgbQ49RNhL0Ns3CBENAR server | OrIa2rQ2K05HlE66puNbHowqlusEkyEnyk9Xn64gHZg4pvONJDsZMix2xA6he server | yJEk8UppeAXfp0u6Deln0616O0zN1ymkW8owDN3FefqwutDuyY0tLzHHg7aDP server | ae2mRQ8XpKeeBa1560KBM6Gis4s2fuixJONsl0eEpxr4EQWZudR7vZnppvgyc server | pcffg3ombkyJS" server | DNS name: "VgoXrcsY7jf54x15CdiMLrGFHFgySvdj7awYKoAbjKuq0DI37ADKU9 server | gsMKGX7U9uuDCaTFMBpvxmSoU1wbR9OG19KTZ5npRdPh4kJoH1Wm4R3yVTVVz server | E56ZygombYFFYGJAvs7T73DI3ihoVn3todqqu8367Veb9n3EfXVrduh2sGfEg server | 43a3TNHZnzWV10TbND9zyX4e0epmJ1bcpu7i0j2S9bZp7VK9CPf5Q4kHAf7Ty server | GgrH5hm1M6pFG" server | DNS name: "GvXsb9uk8RzyCNLsfe2GPlu31FSFzDeRpggPOgDC5BWe9pWRWpk4m1 server | 2Lb5v1K5sbgNGBQ0HfRc8JY3cOF85qKPNxfBz04Z3uAyeEWRF0JYzMvh0moOB server | y2gNol0623Er85VMMU5krJDXwIIPuImPNiuut526augHwlnJRwgNxe2swQVBB server | EIwEfxMnKYcmTcDamDna2UQLdnHZdGJrQir3CxTHRgPLvDzn86Vj8o2adQO4y server | fHowHIJYC6MtF" server | DNS name: "NZuulaCV8796dSfYi9Qan6XLboMCw7sIRaAJv80KkcDnM4dFmOsqHG server | iGhJJZjxgmWf2ROQWIfT6YKGQvRBdV7PVHR7sFxPXDtwJVdLOjd8SIJfmHQqR server | nj4z3Nb4qh2X9qajPfBR1Ft8uctGCOYQEbAmH7Uz0JWKwYLzqHbkICkeRvtNn server | UG5BADTxLYQpJQvlAAeKvTKRMnX3eBiUQtw7p5f07KuyAxEQU93Z0pVPQ30a2 server | FVGduJDDA4Qa2" server | DNS name: "EeK2UBiPL46Hv0PfLk68vwyfCSktyuD92UUFCwkPxaAXfNRLjP7Okw server | UTHrk4MdLbkPdfSnRnjrTsyNp18uCc0EMew9DUakckesAdXUw0Q8yAXYul2DB server | XSh6y4ojnwEr05oz65P3HlrTTQrw9JjIiDfNHFa7a9vkfJEvjPZbpoi4yL0bT server | dtj5Z7I8rWjSeLb5oOMGHt3Wb05tyRp7RFMCpBk1EqbJ1XlVCDmsU1H7FmiZn server | FuatmsiengRqa" server | DNS name: "HOlloPRYZpTvMDKYKSa2O1RJ3eFdtxZmqTwyMZ5983Wvh3ShSU8Jlp server | z36UFS0SByMjSa1mw4c4f22Jd0FFvXCBEH16dJpw59sp4jgYrvnTJai5vFXWo server | 5bR1WDcyvFkjs7hQ24cV5IGAmUyF3UPk19WMjpZHfH6oric2YBX7pkD7RswbA server | 4NEnkWN5z4WjxgQYHfjey2oTIYAAtac2BfxYQKXDmgSZ6XBiSZIBII5tM9WSX server | ZKeSW1HRicPOu" server | DNS name: "mN2n3L5hCiizeBlnzhRsKqIKIeY4Gojbix9JnL552Pvz0A679AW0le server | pCB9OqKn8UW8CM8lta7LJTAhZBDeCDvTQwP9X0QDlQcsasvP5xRtZbhMf9ga3 server | bnc9qUf3R1IDZEY1557SeHFsRiPe675ArwGnuToNiwIUGbxKCP1HedPdOtsjo server | FVA7pqK1SDMyhB3DFt0AWuNVbifOcI9SzJX75JQDjYtqajVJ90wus46nU0Y6r server | V3KHOSV9NujrN" server | DNS name: "CG704pzpqUJQtfkWqfBQwxrLgX0ghOFfdDHyX1NM0o1Fcs5yMoydrv server | aK1uwm3k5piy4IG4ukvMogu4lzPNDWJuFGRkLwv1MZECCJeY13QyJjuzdOtN9 server | V9DydFQhqw8utjjHVxk0a5UyTQYSfTS3Nx9odqZN9mgNzdHm9tAJ6O8QX2xzo server | k71wKBeaUuMCN9AjjdM1xX0PvIPWxMSzuIdttzv2bCLFGnqz9tRKJdAWmFvVL server | BSN4bKPMqYWjt" server | DNS name: "ricky80lFRmd57Yp1PC1bYWRxC7BIpUSzJG4DvzCTsHvyHROnEwbRh server | y9DE4ZOkZ7GJ3IqtuLBTfHfeLhVv5WRAnWVrdmtJsutsZ9fHHnJSz9ElaJJ7A server | kuS5aOFFLYizqPAXcSlyxjhFsdJDI4IABa3tLpZCm6ZCla3HMu5GwsUFgFBTu server | gOCGzJ0qWurI4QiA4Zo440BA5QmU1BUxAy59uR1NnOVoBATxwFbHKXTCaIzfJ server | JpJMSJKcNkHU7" server | DNS name: "eKAczUPnjtGF053UObAFdsuwGXJGe3Qz5W925S2oP0CZm1Vl7CKqpc server | 76xWkfs2iHhyCbdyr5D91opHG3PVzOEwxQiFlv9Jp3CyekRpP5fLEvyWKt9G4 server | G2xDgCIbHh8zahdmUPGEyW1GaWwRd12fjEYn4fmj52P3b1K6aXYD5arH8ANNq server | jbEig8DBJ5HSkXjQazCdNcEHzH2Wt1XnN68gK5yNDWqj21u6nowqYrfR1SnqF server | jHefgUNmdgYbG" server | DNS name: "SeGD9wuPU7FYSgd7RPlI1vaYHwJFe25TEUUfX3T7Ro9F554Ot9m0pl server | tENE0g1qVn3JQ9iOreWDIUBaz9k4I9fomi7E543iajvQqzxvU1p6zZaFbTE3O server | 0ciDvnOTRdcO231ugnSSCOf9uSw8i7UjopjGtWW00IZHgZ7KmgL6pqRjfCU1C server | QH0N4LQ41oQ4Dd8eemzAMQtmZBIqKNVfpY8E9AMOIBE2z8SsbdRq785biNflV server | mir5tiLxTTfnP" server | DNS name: "CzMspuEDqKBm3VkXcr00UkmYSHMhU3ehREoHcqI4KKdCBeM4s9e7LK server | 6HNutINGXJoSXoc2Xge7Jnm0gQ91YCzHadhl2Lbv1DvWClHAA8LsrKOmkN3Kt server | ueG1jkxFzDJitF1gSiJ9dD3voP9YA9wRhjujTJdDps0lWM17Sw8HyhlahEShf server | wmIaahi4vgEHpP1SKsFjMEJbCBZpnNBOzp0BJMqKk7RwRE2v8pAnNnNrl0Iz1 server | zxpMzjndIMArq" server | DNS name: "OTiUN8mzyNxeBLdIhoYzdigkrlRJYIzfAAS3aquBW3IEpFL9nHPuHW server | zXdQG1rMQDiH1SjsxeS6mMfNFgfCNrjYXiA9JDLhEVTozReeRvK0p4hC3tfrv server | Yyoc85n7CQXGpzWrYZAkKkcLuSnBPg5YVvBvU0sYCt4pjLrBuAKkBhqu2gN8S server | rTKBGFpXIcZvq4tPCJEpm6aumFexghV77gM96RR1U6EQ1pfnwL1P9HSWlyNnG server | ZMfqo2ynTAkwE" server | DNS name: "oPRthVhMwNFJQL7RmqlOu1zK04LuiSmS6vxjMrVhdl8HcZcDV7vUYS server | Lrt3iz7p45Yhr5i0LZjdvN8VUVlMGoptucfotxTuXNZ1ZP9x4NQUk5yVyGOIn server | 1dVN0AgKvfkKGzIrpMbcnKmivD9Ji1gO3i2Sy6wspEOdGFLcDqW6iUYQBLZ6X server | TJd418SzgnoJPZDznbSmwrNX8l9ZnNh2ZvYEcRgcDR0pUbRQiTgP7uhHC0XZo server | agQqaDiTdxYnN" server | DNS name: "KPXUrpBSgZH0ooCxyISyQAPzJeQnw4YhZxtSkB4KXHjBB6kciV5KV7 server | cBbvAXmLpXlEALV5Rhze6lsmtNy7sfJhnwfFVAXYsyKFXLi2ajXrRdt6UYzgN server | YWqQAu8Dgmnm1gzaGD5oyirsR6QINzkitp1zOryM83sEDJWpHi4SaPCoaAc1u server | w2B0sNce0R7qHV5CufInQ8nXFTHFEAcek1g7r9LXyGRbvBe4XZmprV9FjSdb7 server | 4DaCemwaR1kVC" server | server | Name: Certificate Subject Key ID server | Data: server | ec:5e:47:6c:db:7b:1a:a2:95:f8:98:a9:9e:b7:01:8d: server | 43:a5:94:8e server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 07:89:c7:2c:55:38:ff:cd:04:9e:45:51:ae:2f:a7:fc: server | a8:ec:ea:63 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:21:00:b7:0e:cf:83:87:57:bb:5d:1c:6e:a7: server | f8:17:fa:45:e1:6d:07:54:0a:1c:aa:73:de:84:cc:ce: server | 06:03:7a:bb:c1:02:20:2f:c8:66:60:c6:29:aa:77:d0: server | 22:92:03:03:78:18:43:66:34:69:c0:81:19:f4:54:5e: server | 24:ab:3f:99:b1:c1:fb server | Fingerprint (SHA-256): server | B5:D0:D9:27:E2:1C:02:71:91:A5:7A:3D:3E:8F:B9:73:7D:77:71:C9:1B:2C:3F:3E:32:EB:18:AA:08:B6:05:C6 server | Fingerprint (SHA1): server | 5E:A3:76:09:0E:82:3C:F4:3B:76:DC:6E:7F:D1:C0:F9:69:0E:AB:17 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.003 DEBUG Default socket receive buffer size is 1048576, not changing server | 0.003 INFO Server waiting for connection on: [::]:443 server | 0.759 DEBUG [Server] Unsupported version: 57414954 server | 0.759 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:57609 IpTos(Cs0, NotEct) len 15 server | 0.759 DEBUG [Server] Unsupported version: 57414954 sim | server:443 is available after 1.023647084s server | 0.759 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:57609 IpTos(Cs0, NotEct) len 15 server | 0.759 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.759 DEBUG [Server] Unsupported version: 57414954 server | 0.759 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:57609 IpTos(Cs0, NotEct) len 15 server | 0.759 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 eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | Connecting to server4 client | Client params (before files):-test:D -timeout:50000 -sslkeylogfile:/logs/keys.log client | Sending request: GET /ojshxnianm server | 1.010 DEBUG [Server] Handle initial server | 1.010 INFO AddressValidation: no token; accepting server | 1.010 INFO [Server] Accept connection CID [8]: 89a971f8c3dca9a5 server | 1.010 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.014 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.014 INFO [Server ...] Received valid Initial packet with scid CID [0]: dcid CID [8]: 89a971f8c3dca9a5 server | 1.014 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=89a971f8c3dca9a5 server | 1.014 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.014 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.014 DEBUG [Server 89a971f8c3dca9a5] State change from Init -> WaitInitial server | 1.014 DEBUG [Server 89a971f8c3dca9a5] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1220 server | -> RX Crypto { offset: 0, len: 290 } server | -> RX Padding { len: 888 } server | 1.014 DEBUG Read Ok(290) bytes server | 1.014 DEBUG 0-RTT: no token, no 0-RTT server | 1.014 DEBUG Read secret available for Handshake: SymKey [32]: e847c1fa75c1a4e21dcbd73eb873e7abf3c7d29816e9f8e1e3a619c6f86a6b26 server | 1.014 DEBUG Write secret available for Handshake: SymKey [32]: b89de4d9fa6b0b2ba96b1e11074e8999e81c7b90106163c248bd5c8f8f9a5323 server | 1.014 DEBUG Writing transport parameters, msg=8 server | 1.015 DEBUG Read secret available for ApplicationData: SymKey [32]: ae0cda8a64173559eb32880664d3f5eb72b85ed257c0eff3e1705ba95cd3eea7 server | 1.015 DEBUG Write secret available for ApplicationData: SymKey [32]: 033b350f4d65dccf626a859b7980d4429bcc061cc28ae00c14109090942419fb server | 1.015 DEBUG [Agent 0x560ab84811d0] state -> InProgress server | 1.015 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.015 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.015 DEBUG [Crypto] Handshake keys installed server | 1.015 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.015 DEBUG [Crypto] Application write key installed server | 1.015 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 497, tv_nsec: 249810072 } server | 1.015 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:60056] Make permanent server | 1.015 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:60056] set as primary path server | 1.015 DEBUG [Server 89a971f8c3dca9a5] State change from WaitInitial -> Handshaking server | 1.015 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.015 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG CRYPTO for in offset=0, len=90 server | 1.015 DEBUG [Server 89a971f8c3dca9a5] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 136 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 1.015 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG CRYPTO for hs offset=0, len=1056 server | 1.015 DEBUG [Server 89a971f8c3dca9a5] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 0, len: 1056 } server | 1.015 DEBUG packet_sent this=0x560ab8480b40, pn=0, ps=1096 server | 1.015 DEBUG Building Short dcid Some(CID [0]: ) server | 1.015 DEBUG packet_sent this=0x560ab8480b40, pn=0, ps=136 server | 1.015 DEBUG ECN probing: sent 1 probes server | 1.015 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.015 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG CRYPTO for hs offset=1056, len=1191 server | 1.015 DEBUG [Server 89a971f8c3dca9a5] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 1056, len: 1191 } server | 1.015 DEBUG packet_sent this=0x560ab8480b40, pn=1, ps=1232 server | 1.015 DEBUG Building Short dcid Some(CID [0]: ) server | 1.015 DEBUG ECN probing: sent 2 probes server | 1.015 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1196, pto: None, probe: EnumSet(), paced: false } server | 1.015 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG CRYPTO for hs offset=2247, len=1155 server | 1.015 DEBUG [Server 89a971f8c3dca9a5] pn=2 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1196 server | TX -> Crypto { offset: 2247, len: 1155 } server | 1.015 DEBUG packet_sent this=0x560ab8480b40, pn=2, ps=1196 server | 1.015 DEBUG Building Short dcid Some(CID [0]: ) server | 1.015 DEBUG ECN probing: sent 3 probes server | 1.015 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.015 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG Building Short dcid Some(CID [0]: ) server | 1.015 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.015 DEBUG [Server 89a971f8c3dca9a5] delay duration 29.994957318s server | 1.015 DEBUG Setting timeout of 29.994957318s server | 1.015 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.015 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 1.015 DEBUG Building Short dcid Some(CID [0]: ) server | 1.015 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.015 DEBUG [Server 89a971f8c3dca9a5] delay duration 29.994929256s server | 1.015 DEBUG Setting timeout of 29.994929256s sim | Dropping packet 2 (1220 bytes) from 193.167.0.100 sim | Dropping packet 3 (48 bytes) from 193.167.0.100 sim | Dropping packet 4 (1220 bytes) from 193.167.0.100 sim | Dropping packet 5 (1220 bytes) from 193.167.0.100 sim | Dropping packet 6 (1220 bytes) from 193.167.0.100 sim | Dropping packet 7 (1220 bytes) from 193.167.0.100 server | 5.577 DEBUG [Server 89a971f8c3dca9a5] Drop packet number space in server | 5.577 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 5.577 INFO [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:60056] discarding a packet without an RTT estimate; guessing RTT=4.566910698s server | 5.577 DEBUG [Server 89a971f8c3dca9a5] pn=8 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1220 server | -> RX Ping server | -> RX Padding { len: 1180 } server | 5.577 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 501, tv_nsec: 816720770 } server | 5.577 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 501, tv_nsec: 816720770 } server | 5.577 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 501, tv_nsec: 816720770 } server | 5.577 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.577 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.577 DEBUG CRYPTO for hs offset=0, len=1187 server | 5.577 DEBUG [Server 89a971f8c3dca9a5] pn=3 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Ack { largest_acknowledged: 8, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 1187 } server | 5.577 DEBUG packet_sent this=0x560ab8480b40, pn=3, ps=1232 server | 5.577 DEBUG Building Short dcid Some(CID [0]: ) server | 5.577 DEBUG ECN probing: sent 4 probes server | 5.577 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.577 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.577 DEBUG CRYPTO for hs offset=1187, len=1191 server | 5.577 DEBUG [Server 89a971f8c3dca9a5] pn=4 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 1187, len: 1191 } server | 5.577 DEBUG packet_sent this=0x560ab8480b40, pn=4, ps=1232 server | 5.577 DEBUG Building Short dcid Some(CID [0]: ) server | 5.577 DEBUG ECN probing: sent 5 probes server | 5.577 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.577 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.577 DEBUG CRYPTO for hs offset=2378, len=1191 server | 5.577 DEBUG [Server 89a971f8c3dca9a5] pn=5 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 2378, len: 1191 } server | 5.577 DEBUG packet_sent this=0x560ab8480b40, pn=5, ps=1232 server | 5.577 DEBUG Building Short dcid Some(CID [0]: ) server | 5.577 DEBUG ECN probing: sent 6 probes server | 5.577 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.577 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.577 DEBUG Building Short dcid Some(CID [0]: ) server | 5.577 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.577 DEBUG [Server 89a971f8c3dca9a5] delay duration 228.062627ms server | 5.577 DEBUG Setting timeout of 228.062627ms server | 5.577 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.577 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.577 DEBUG Building Short dcid Some(CID [0]: ) server | 5.577 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.577 DEBUG [Server 89a971f8c3dca9a5] delay duration 228.027942ms server | 5.577 DEBUG Setting timeout of 228.027942ms server | 5.610 DEBUG [Server 89a971f8c3dca9a5] pn=9 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1220 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | -> RX Ping server | -> RX Padding { len: 1172 } server | 5.610 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=hs, ranges=[0..=3] server | 5.610 DEBUG [LossRecovery] ACK for Handshake - largest_acked=3 server | 5.610 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=2464, cwnd=12320, state=SlowStart, new_acked=4756 server | 5.610 DEBUG Acked crypto frame space=hs offset=0 length=1187 server | 5.610 DEBUG Acked crypto frame space=hs offset=2247 length=1155 server | 5.610 DEBUG Acked crypto frame space=hs offset=1056 length=1191 server | 5.610 DEBUG Acked crypto frame space=hs offset=0 length=1056 server | 5.610 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 501, tv_nsec: 849506936 } server | 5.610 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 501, tv_nsec: 849506936 } server | 5.610 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 501, tv_nsec: 849506936 } server | 5.610 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.610 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.610 DEBUG [Server 89a971f8c3dca9a5] pn=6 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 41 server | TX -> Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 5.610 DEBUG Building Short dcid Some(CID [0]: ) server | 5.610 DEBUG ECN probing: sent 7 probes server | 5.610 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.610 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.610 DEBUG Building Short dcid Some(CID [0]: ) server | 5.610 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.610 DEBUG [Server 89a971f8c3dca9a5] delay duration 173.813921ms server | 5.610 DEBUG Setting timeout of 173.813921ms server | 5.610 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.610 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.610 DEBUG Building Short dcid Some(CID [0]: ) server | 5.610 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.610 DEBUG [Server 89a971f8c3dca9a5] delay duration 173.786399ms server | 5.610 DEBUG Setting timeout of 173.786399ms server | 5.611 DEBUG [Server 89a971f8c3dca9a5] pn=10 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 47 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } server | 5.611 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=hs, ranges=[0..=5] server | 5.611 DEBUG [LossRecovery] ACK for Handshake - largest_acked=5 server | 5.611 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=2464 server | 5.611 DEBUG Acked crypto frame space=hs offset=2378 length=1191 server | 5.611 DEBUG Acked crypto frame space=hs offset=1187 length=1191 server | 5.611 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 501, tv_nsec: 850455452 } server | 5.611 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.611 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.611 DEBUG CRYPTO for hs offset=3569, len=1191 server | 5.611 DEBUG [Server 89a971f8c3dca9a5] pn=7 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 3569, len: 1191 } server | 5.611 DEBUG packet_sent this=0x560ab8480b40, pn=7, ps=1232 server | 5.611 DEBUG Building Short dcid Some(CID [0]: ) server | 5.611 DEBUG ECN probing: sent 8 probes server | 5.611 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.611 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.611 DEBUG Building Short dcid Some(CID [0]: ) server | 5.611 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.611 DEBUG [Server 89a971f8c3dca9a5] delay duration 175.107259ms server | 5.611 DEBUG Setting timeout of 175.107259ms server | 5.611 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.611 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.611 DEBUG Building Short dcid Some(CID [0]: ) server | 5.611 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.611 DEBUG [Server 89a971f8c3dca9a5] delay duration 175.082914ms server | 5.611 DEBUG Setting timeout of 175.082914ms server | 5.668 DEBUG [Server 89a971f8c3dca9a5] pn=11 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 48 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 98, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } server | 5.668 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=hs, ranges=[4..=7] server | 5.668 DEBUG [LossRecovery] ACK for Handshake - largest_acked=7 server | 5.668 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 5.668 DEBUG Acked crypto frame space=hs offset=3569 length=1191 server | 5.668 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 501, tv_nsec: 907215805 } server | 5.668 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.668 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.668 DEBUG CRYPTO for hs offset=4760, len=1191 server | 5.668 DEBUG [Server 89a971f8c3dca9a5] pn=8 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 4760, len: 1191 } server | 5.668 DEBUG packet_sent this=0x560ab8480b40, pn=8, ps=1232 server | 5.668 DEBUG Building Short dcid Some(CID [0]: ) server | 5.668 DEBUG ECN probing: sent 9 probes server | 5.668 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.668 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.668 DEBUG Building Short dcid Some(CID [0]: ) server | 5.668 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.668 DEBUG [Server 89a971f8c3dca9a5] delay duration 153.46853ms server | 5.668 DEBUG Setting timeout of 153.46853ms server | 5.668 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.668 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.668 DEBUG Building Short dcid Some(CID [0]: ) server | 5.668 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.668 DEBUG [Server 89a971f8c3dca9a5] delay duration 153.432463ms server | 5.668 DEBUG Setting timeout of 153.432463ms server | 5.724 DEBUG [Server 89a971f8c3dca9a5] pn=12 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 48 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 98, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | 5.724 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=hs, ranges=[4..=8] server | 5.724 DEBUG [LossRecovery] ACK for Handshake - largest_acked=8 server | 5.724 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 5.724 DEBUG Acked crypto frame space=hs offset=4760 length=1191 server | 5.724 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 501, tv_nsec: 964047394 } server | 5.724 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.724 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.724 DEBUG CRYPTO for hs offset=5951, len=1191 server | 5.724 DEBUG [Server 89a971f8c3dca9a5] pn=9 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 5951, len: 1191 } server | 5.724 DEBUG packet_sent this=0x560ab8480b40, pn=9, ps=1232 server | 5.724 DEBUG Building Short dcid Some(CID [0]: ) server | 5.724 DEBUG ECN probing: sent 10 probes server | 5.724 DEBUG ECN probing concluded with 10 probes sent server | 5.725 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.725 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.725 DEBUG Building Short dcid Some(CID [0]: ) server | 5.725 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.725 DEBUG [Server 89a971f8c3dca9a5] delay duration 134.650791ms server | 5.725 DEBUG Setting timeout of 134.650791ms server | 5.725 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.725 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.725 DEBUG Building Short dcid Some(CID [0]: ) server | 5.725 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.725 DEBUG [Server 89a971f8c3dca9a5] delay duration 134.617689ms server | 5.725 DEBUG Setting timeout of 134.617689ms server | 5.735 DEBUG [Server 89a971f8c3dca9a5] pn=13 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1220 server | -> RX Ping server | -> RX Padding { len: 1180 } server | 5.736 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 501, tv_nsec: 975171553 } server | 5.736 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 501, tv_nsec: 975171553 } server | 5.736 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 501, tv_nsec: 975171553 } server | 5.736 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.736 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.736 DEBUG [Server 89a971f8c3dca9a5] pn=10 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 41 server | TX -> Ack { largest_acknowledged: 13, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 5.736 DEBUG Building Short dcid Some(CID [0]: ) server | 5.736 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.736 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.736 DEBUG Building Short dcid Some(CID [0]: ) server | 5.736 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.736 DEBUG [Server 89a971f8c3dca9a5] delay duration 128.159195ms server | 5.736 DEBUG Setting timeout of 128.159195ms server | 5.736 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.736 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.736 DEBUG Building Short dcid Some(CID [0]: ) server | 5.736 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.736 DEBUG [Server 89a971f8c3dca9a5] delay duration 128.135471ms server | 5.736 DEBUG Setting timeout of 128.135471ms server | 5.781 DEBUG [Server 89a971f8c3dca9a5] pn=14 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 47 server | -> RX Ack { largest_acknowledged: 10, ack_delay: 59, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) } server | 5.781 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=hs, ranges=[9..=10] server | 5.781 DEBUG [LossRecovery] ACK for Handshake - largest_acked=10 server | 5.781 INFO ECN validation succeeded, path is capable server | 5.781 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 5.781 DEBUG Acked crypto frame space=hs offset=5951 length=1191 server | 5.781 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 502, tv_nsec: 21041568 } server | 5.781 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.781 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.781 DEBUG CRYPTO for hs offset=7142, len=1191 server | 5.781 DEBUG [Server 89a971f8c3dca9a5] pn=11 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 7142, len: 1191 } server | 5.781 DEBUG packet_sent this=0x560ab8480b40, pn=11, ps=1232 server | 5.781 DEBUG Building Short dcid Some(CID [0]: ) server | 5.782 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.782 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.782 DEBUG Building Short dcid Some(CID [0]: ) server | 5.782 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.782 DEBUG [Server 89a971f8c3dca9a5] delay duration 118.035754ms server | 5.782 DEBUG Setting timeout of 118.035754ms server | 5.782 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.782 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.782 DEBUG Building Short dcid Some(CID [0]: ) server | 5.782 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.782 DEBUG [Server 89a971f8c3dca9a5] delay duration 117.982354ms server | 5.782 DEBUG Setting timeout of 117.982354ms server | 5.838 DEBUG [Server 89a971f8c3dca9a5] pn=15 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 48 server | -> RX Ack { largest_acknowledged: 11, ack_delay: 98, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 11, Ce: 0})) } server | 5.838 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=hs, ranges=[9..=11] server | 5.838 DEBUG [LossRecovery] ACK for Handshake - largest_acked=11 server | 5.838 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 5.838 DEBUG Acked crypto frame space=hs offset=7142 length=1191 server | 5.838 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 502, tv_nsec: 77917738 } server | 5.838 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.838 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.838 DEBUG CRYPTO for hs offset=8333, len=1191 server | 5.838 DEBUG [Server 89a971f8c3dca9a5] pn=12 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 8333, len: 1191 } server | 5.838 DEBUG packet_sent this=0x560ab8480b40, pn=12, ps=1232 server | 5.838 DEBUG Building Short dcid Some(CID [0]: ) server | 5.838 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.838 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.838 DEBUG Building Short dcid Some(CID [0]: ) server | 5.838 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.838 DEBUG [Server 89a971f8c3dca9a5] delay duration 103.644488ms server | 5.838 DEBUG Setting timeout of 103.644488ms server | 5.838 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.838 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.838 DEBUG Building Short dcid Some(CID [0]: ) server | 5.838 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.838 DEBUG [Server 89a971f8c3dca9a5] delay duration 103.611376ms server | 5.838 DEBUG Setting timeout of 103.611376ms server | 5.852 DEBUG [Server 89a971f8c3dca9a5] pn=16 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1220 server | -> RX Ping server | -> RX Padding { len: 1180 } server | 5.852 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 502, tv_nsec: 92062382 } server | 5.852 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 502, tv_nsec: 92062382 } server | 5.852 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 502, tv_nsec: 92062382 } server | 5.852 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.852 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.852 DEBUG [Server 89a971f8c3dca9a5] pn=13 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 41 server | TX -> Ack { largest_acknowledged: 16, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: None } server | 5.852 DEBUG Building Short dcid Some(CID [0]: ) server | 5.853 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.853 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.853 DEBUG Building Short dcid Some(CID [0]: ) server | 5.853 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.853 DEBUG [Server 89a971f8c3dca9a5] delay duration 93.027712ms server | 5.853 DEBUG Setting timeout of 93.027712ms server | 5.853 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.853 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.853 DEBUG Building Short dcid Some(CID [0]: ) server | 5.853 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.853 DEBUG [Server 89a971f8c3dca9a5] delay duration 92.992997ms server | 5.853 DEBUG Setting timeout of 92.992997ms server | 5.895 DEBUG [Server 89a971f8c3dca9a5] pn=17 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 47 server | -> RX Ack { largest_acknowledged: 13, ack_delay: 47, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 13, Ce: 0})) } server | 5.895 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=hs, ranges=[12..=13] server | 5.895 DEBUG [LossRecovery] ACK for Handshake - largest_acked=13 server | 5.895 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 5.895 DEBUG Acked crypto frame space=hs offset=8333 length=1191 server | 5.895 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 502, tv_nsec: 134981041 } server | 5.895 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.895 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.895 DEBUG CRYPTO for hs offset=9524, len=446 server | 5.895 DEBUG [Server 89a971f8c3dca9a5] pn=14 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 487 server | TX -> Crypto { offset: 9524, len: 446 } server | 5.895 DEBUG packet_sent this=0x560ab8480b40, pn=14, ps=487 server | 5.895 DEBUG Building Short dcid Some(CID [0]: ) server | 5.896 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.896 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.896 DEBUG Building Short dcid Some(CID [0]: ) server | 5.896 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.896 DEBUG [Server 89a971f8c3dca9a5] delay duration 74.691342ms server | 5.896 DEBUG Setting timeout of 74.691342ms server | 5.896 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.896 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.896 DEBUG Building Short dcid Some(CID [0]: ) server | 5.896 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.896 DEBUG [Server 89a971f8c3dca9a5] delay duration 74.658771ms server | 5.896 DEBUG Setting timeout of 74.658771ms server | 5.928 DEBUG [Server 89a971f8c3dca9a5] pn=18 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 86 server | -> RX Ack { largest_acknowledged: 14, ack_delay: 4, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 14, Ce: 0})) } server | -> RX Crypto { offset: 0, len: 36 } server | 5.928 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=hs, ranges=[12..=14] server | 5.928 DEBUG [LossRecovery] ACK for Handshake - largest_acked=14 server | 5.928 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=487 server | 5.928 DEBUG Acked crypto frame space=hs offset=9524 length=446 server | 5.928 DEBUG Read Ok(36) bytes server | 5.928 DEBUG [Agent 0x560ab84811d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 5.928 DEBUG [Server 89a971f8c3dca9a5] TLS connection complete server | 5.928 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 502, tv_nsec: 167240012 } server | 5.928 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 5.928 DEBUG [Crypto] application read keys installed server | 5.928 DEBUG [Server 89a971f8c3dca9a5] State change from Handshaking -> Connected server | 5.928 DEBUG [Server 89a971f8c3dca9a5] State change from Connected -> Confirmed server | 5.928 DEBUG PMTUD started with probe size 1380 server | 5.928 INFO [Server 89a971f8c3dca9a5] Connection established server | 5.928 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 502, tv_nsec: 167240012 } server | 5.928 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.928 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b9a2c7a7f53443b7f13f) server | 5.928 DEBUG [Server 89a971f8c3dca9a5] pn=15 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 41 server | TX -> Ack { largest_acknowledged: 18, ack_delay: 0, first_ack_range: 10, ack_ranges: [], ecn_count: None } server | 5.928 DEBUG [Server 89a971f8c3dca9a5] Drop packet number space hs server | 5.928 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 5.928 DEBUG Building Short dcid Some(CID [0]: ) server | 5.928 DEBUG [Server 89a971f8c3dca9a5] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [181, 223, 110, 89, 30, 119, 206, 152, 152, 234], stateless_reset_token: [179, 159, 112, 99, 239, 191, 6, 193, 69, 93, 47, 212, 154, 191, 87, 201] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [67, 253, 214, 160, 132, 180, 32, 103, 183, 236], stateless_reset_token: [11, 93, 65, 252, 104, 48, 73, 150, 202, 23, 30, 81, 63, 193, 3, 93] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [20, 210, 246, 234, 200, 113, 103, 60, 20, 201], stateless_reset_token: [204, 143, 89, 14, 123, 3, 171, 26, 51, 220, 40, 120, 182, 255, 150, 115] } server | TX -> Padding { len: 1182 } server | 5.928 DEBUG packet_sent this=0x560ab8480b40, pn=0, ps=1291 server | 5.928 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.928 DEBUG Building Short dcid Some(CID [0]: ) server | 5.928 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.928 DEBUG [Server 89a971f8c3dca9a5] delay duration 79.421924ms server | 5.928 DEBUG Setting timeout of 79.421924ms server | 5.929 DEBUG [Server 89a971f8c3dca9a5] send session ticket 4063000889a971f8c3dca9a50104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0ab9a2c7a7f53443b7f13f110c000000014a9a1a2a000000016ab200c0000000ff02de1a0243e8200100686921 server | 5.929 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.929 DEBUG Building Short dcid Some(CID [0]: ) server | 5.929 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.929 DEBUG [Server 89a971f8c3dca9a5] delay duration 78.412434ms server | 5.929 DEBUG Setting timeout of 78.412434ms server | 5.929 DEBUG [Server 89a971f8c3dca9a5] pn=19 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1252 server | -> RX Ping server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Padding { len: 1199 } server | 5.929 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 502, tv_nsec: 168800586 } server | 5.929 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 502, tv_nsec: 168800586 } server | 5.929 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.929 DEBUG Building Short dcid Some(CID [0]: ) server | 5.929 DEBUG [Server 89a971f8c3dca9a5] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 23 server | TX -> Ack { largest_acknowledged: 19, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 5.929 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.929 DEBUG Building Short dcid Some(CID [0]: ) server | 5.929 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.929 DEBUG [Server 89a971f8c3dca9a5] delay duration 79.766097ms server | 5.929 DEBUG Setting timeout of 79.766097ms server | 5.929 DEBUG Path = 'ojshxnianm' server | 5.929 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.929 DEBUG Building Short dcid Some(CID [0]: ) server | 5.929 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.929 DEBUG [Server 89a971f8c3dca9a5] delay duration 79.677541ms server | 5.929 DEBUG Setting timeout of 79.677541ms server | 5.961 DEBUG [Server 89a971f8c3dca9a5] pn=20 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1332 server | -> RX Ping server | -> RX Padding { len: 1300 } server | 5.961 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 502, tv_nsec: 200427902 } server | 5.961 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 502, tv_nsec: 200427902 } server | 5.961 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.961 DEBUG Building Short dcid Some(CID [0]: ) server | 5.961 DEBUG [Server 89a971f8c3dca9a5] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 23 server | TX -> Ack { largest_acknowledged: 20, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 5.961 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.961 DEBUG Building Short dcid Some(CID [0]: ) server | 5.961 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.961 DEBUG [Server 89a971f8c3dca9a5] delay duration 49.586257ms server | 5.961 DEBUG Setting timeout of 49.586257ms server | 5.961 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.961 DEBUG Building Short dcid Some(CID [0]: ) server | 5.961 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.961 DEBUG [Server 89a971f8c3dca9a5] delay duration 49.552894ms server | 5.961 DEBUG Setting timeout of 49.552894ms server | 5.985 DEBUG [Server 89a971f8c3dca9a5] pn=21 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 40 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 98, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } server | 5.985 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=ap, ranges=[0..=1] server | 5.985 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 5.985 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1291 server | 5.985 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.985 DEBUG Building Short dcid Some(CID [0]: ) server | 5.985 DEBUG Sending PMTUD probe of size 1380, count 1 server | 5.985 DEBUG [Server 89a971f8c3dca9a5] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 0, len: >>1311, fin: false } server | 5.985 DEBUG packet_sent this=0x560ab8480b40, pn=3, ps=1332 server | 5.985 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.985 DEBUG Building Short dcid Some(CID [0]: ) server | 5.985 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.985 DEBUG [Server 89a971f8c3dca9a5] delay duration 70.105125ms server | 5.985 DEBUG Setting timeout of 70.105125ms server | 5.985 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.985 DEBUG Building Short dcid Some(CID [0]: ) server | 5.985 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.985 DEBUG [Server 89a971f8c3dca9a5] delay duration 70.037806ms server | 5.985 DEBUG Setting timeout of 70.037806ms server | 5.992 DEBUG [Server 89a971f8c3dca9a5] pn=22 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1412 server | -> RX Ping server | -> RX Padding { len: 1380 } server | 5.992 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 502, tv_nsec: 232120274 } server | 5.992 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 502, tv_nsec: 232120274 } server | 5.992 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.992 DEBUG Building Short dcid Some(CID [0]: ) server | 5.992 DEBUG [Server 89a971f8c3dca9a5] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 23 server | TX -> Ack { largest_acknowledged: 22, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 5.993 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.993 DEBUG Building Short dcid Some(CID [0]: ) server | 5.993 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.993 DEBUG [Server 89a971f8c3dca9a5] delay duration 63.973032ms server | 5.993 DEBUG Setting timeout of 63.973032ms server | 5.993 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.993 DEBUG Building Short dcid Some(CID [0]: ) server | 5.993 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.993 DEBUG [Server 89a971f8c3dca9a5] delay duration 63.946753ms server | 5.993 DEBUG Setting timeout of 63.946753ms client | ojshxnianm: Wrote 1311 bytes.(0 ms/0 ms/0 ms) server | 6.024 DEBUG [Server 89a971f8c3dca9a5] pn=23 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 1472 server | -> RX Ping server | -> RX Padding { len: 1440 } server | 6.024 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 502, tv_nsec: 263802895 } server | 6.024 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 502, tv_nsec: 263802895 } server | 6.024 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.024 DEBUG Building Short dcid Some(CID [0]: ) server | 6.024 DEBUG [Server 89a971f8c3dca9a5] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 23 server | TX -> Ack { largest_acknowledged: 23, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: None } server | 6.024 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.024 DEBUG Building Short dcid Some(CID [0]: ) server | 6.024 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.024 DEBUG [Server 89a971f8c3dca9a5] delay duration 33.608353ms server | 6.024 DEBUG Setting timeout of 33.608353ms server | 6.024 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.024 DEBUG Building Short dcid Some(CID [0]: ) server | 6.024 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.024 DEBUG [Server 89a971f8c3dca9a5] delay duration 33.578877ms server | 6.024 DEBUG Setting timeout of 33.578877ms server | 6.042 DEBUG [Server 89a971f8c3dca9a5] pn=24 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 40 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 74, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | 6.042 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=ap, ranges=[2..=4] server | 6.042 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 6.042 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1332 server | 6.042 DEBUG PMTUD probe of size 1380 succeeded server | 6.042 DEBUG PMTUD started with probe size 1420 server | 6.042 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 6.042 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 6.042 DEBUG Building Short dcid Some(CID [0]: ) server | 6.042 DEBUG Sending PMTUD probe of size 1420, count 1 server | 6.042 DEBUG [Server 89a971f8c3dca9a5] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 1311, len: >>1349, fin: false } server | 6.042 DEBUG packet_sent this=0x560ab8480b40, pn=6, ps=1372 server | 6.042 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.042 DEBUG Building Short dcid Some(CID [0]: ) server | 6.042 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.042 DEBUG [Server 89a971f8c3dca9a5] delay duration 66.624424ms server | 6.042 DEBUG Setting timeout of 66.624424ms server | 6.042 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.042 DEBUG Building Short dcid Some(CID [0]: ) server | 6.042 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.042 DEBUG [Server 89a971f8c3dca9a5] delay duration 66.600509ms server | 6.042 DEBUG Setting timeout of 66.600509ms client | ojshxnianm: Wrote 1349 bytes.(59 ms/59 ms/59 ms) server | 6.100 DEBUG [Server 89a971f8c3dca9a5] pn=25 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 40 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 98, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } server | 6.100 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=ap, ranges=[2..=6] server | 6.100 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6 server | 6.100 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1372 server | 6.100 DEBUG PMTUD probe of size 1420 succeeded server | 6.100 DEBUG PMTUD started with probe size 1470 server | 6.100 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 6.100 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 6.100 DEBUG Building Short dcid Some(CID [0]: ) server | 6.100 DEBUG Sending PMTUD probe of size 1470, count 1 server | 6.100 DEBUG [Server 89a971f8c3dca9a5] pn=7 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1422 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 2660, len: >>1399, fin: false } server | 6.100 DEBUG packet_sent this=0x560ab8480b40, pn=7, ps=1422 server | 6.100 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.100 DEBUG Building Short dcid Some(CID [0]: ) server | 6.100 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.100 DEBUG [Server 89a971f8c3dca9a5] delay duration 60.289184ms server | 6.100 DEBUG Setting timeout of 60.289184ms server | 6.100 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.100 DEBUG Building Short dcid Some(CID [0]: ) server | 6.100 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.100 DEBUG [Server 89a971f8c3dca9a5] delay duration 60.261562ms server | 6.100 DEBUG Setting timeout of 60.261562ms client | ojshxnianm: Wrote 1399 bytes.(116 ms/57 ms/-2 ms) server | 6.157 DEBUG [Server 89a971f8c3dca9a5] pn=26 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 40 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 98, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } server | 6.157 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=ap, ranges=[2..=7] server | 6.157 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7 server | 6.157 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1422 server | 6.157 DEBUG PMTUD probe of size 1470 succeeded server | 6.157 DEBUG PMTUD started with probe size 1500 server | 6.157 DEBUG PLPMTU changed from 1372 to 1422, updating pacer server | 6.157 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 6.157 DEBUG Building Short dcid Some(CID [0]: ) server | 6.157 DEBUG Sending PMTUD probe of size 1500, count 1 server | 6.157 DEBUG CRYPTO for ap offset=0, len=305 server | 6.157 DEBUG [Server 89a971f8c3dca9a5] pn=8 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 1452 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 4059, len: 1061, fin: true } server | TX -> Crypto { offset: 0, len: 305 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 146, 136, 221, 246, 39, 217, 37, 225, 61, 16, 91, 210, 187, 62, 209, 151, 32, 209, 115, 20, 36, 169, 186, 6, 50, 177, 39, 87, 187, 23, 24, 156, 64, 214, 140, 45] } server | TX -> Padding { len: 12 } server | 6.157 DEBUG packet_sent this=0x560ab8480b40, pn=8, ps=1452 server | 6.157 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.157 DEBUG Building Short dcid Some(CID [0]: ) server | 6.157 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.157 DEBUG [Server 89a971f8c3dca9a5] delay duration 54.545177ms server | 6.157 DEBUG Setting timeout of 54.545177ms server | 6.157 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.157 DEBUG Building Short dcid Some(CID [0]: ) server | 6.157 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.157 DEBUG [Server 89a971f8c3dca9a5] delay duration 54.515392ms server | 6.157 DEBUG Setting timeout of 54.515392ms client | ojshxnianm: Wrote 1061 bytes.(173 ms/57 ms/0 ms) client | ojshxnianm: Completed download! (173 ms) server | 6.189 DEBUG [Server 89a971f8c3dca9a5] pn=27 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, NotEct) len 42 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 1, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" } server | 6.189 DEBUG [Server 89a971f8c3dca9a5] Rx ACK space=ap, ranges=[2..=8] server | 6.189 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=8 server | 6.189 DEBUG on_packets_acked this=0x560ab8480b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1452 server | 6.189 DEBUG PMTUD probe of size 1500 succeeded server | 6.189 INFO PMTUD stopped, PLPMTU is now 1500, raise timer Some(Instant { tv_sec: 1102, tv_nsec: 429034208 }) server | 6.189 DEBUG PLPMTU changed from 1422 to 1452, updating pacer server | 6.189 DEBUG Acked crypto frame space=ap offset=0 length=305 server | 6.189 INFO [Server 89a971f8c3dca9a5] ConnectionClose received. Error code: Application(0) frame type 0 reason server | 6.189 DEBUG [Server 89a971f8c3dca9a5] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 522, tv_nsec: 259163289 } } server | 6.189 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:60056] Path validated Instant { tv_sec: 502, tv_nsec: 429034208 } server | 6.189 DEBUG [Server 89a971f8c3dca9a5] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 6.189 DEBUG Building Short dcid Some(CID [0]: ) server | 6.189 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 502, tv_nsec: 429034208 } server | 6.189 DEBUG [Server 89a971f8c3dca9a5] pn=9 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:60056 IpTos(Cs0, Ect0) len 27 server | TX -> Ack { largest_acknowledged: 27, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 6.190 DEBUG Setting timeout of 19.829918827s server | 6.190 DEBUG Setting timeout of 19.829904701s client | client | TARGET VHDCRZSQUMBGA2E VERSION ALPN client | ============================================ client | server4 --D------------ 0x00000001 hq-interop client | client | Total execution time: 5.217s client | client | Client complete. client | Script complete. client exited with code 0 Aborting on container exit... Container server Stopping Container client Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 11:50:05,103 Using the client's key log file. 2025-04-30 11:50:05,112 2025-04-30 11:50:05,113 Using the client's key log file. 2025-04-30 11:50:05,113 Using the client's key log file. 2025-04-30 11:50:05,121 2025-04-30 11:50:05,121 Using the client's key log file. 2025-04-30 11:50:05,121 Using the client's key log file. 2025-04-30 11:50:05,778 Check of downloaded files succeeded. 2025-04-30 11:50:06,107 Server sent 9970 bytes in Handshake CRYPTO frames. 2025-04-30 11:50:06,489 Received a 1220 byte Initial packet from the client. Amplification limit: 3660 2025-04-30 11:50:06,489 Received a 1232 byte Handshake packet from the server. Total: 1232 2025-04-30 11:50:06,489 Received a 1232 byte Handshake packet from the server. Total: 2464 2025-04-30 11:50:06,489 Received a 1196 byte Handshake packet from the server. Total: 3660