2025-04-30 08:46:06,452 Generated random file: jwxecdtvwr of size: 5120 2025-04-30 08:46:06,453 Requests: https://server4:443/jwxecdtvwr 2025-04-30 08:46:06,841 2025-04-30 08:46:06,842 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_jgczkqf5/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_ow3k1shc/ DOWNLOADS=/tmp/download_45p_5nzr/ SERVER_LOGS=/tmp/logs_server_i1gkw4_j CLIENT_LOGS=/tmp/logs_client_204mjd1l SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=privateoctopus/picoquic:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/jwxecdtvwr" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 08:46:20,751 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 client | Setting up the simulation with setup.sh client | Setting up routes... server | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Setup.sh completed client | Waiting for the simulator to start 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.AiRSmlUn5Z server | + mkdir -p /neqo/db server | + certutil -N -d sql:/neqo/db --empty-password client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.AiRSmlUn5Z server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.AiRSmlUn5Z -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 | 04:69:1a:2f:09:32:d0:15:2d:03:ac:30:64:69:f7:62: server | f4:2d:08:c0 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner intermediate 8" server | Validity: server | Not Before: Wed Apr 30 08:46:06 2025 server | Not After : Sat May 10 08:46:06 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:1b:00:3f:19:50:24:00:dc:76:2d:c3:24:80:15:a2: server | e3:2a:b3:f7:b3:8a:b3:f1:e0:bf:78:a8:9a:71:c7:f0: server | e8:3e:0b:3d:89:9a:63:d7:70:31:ec:25:62:74:93:43: server | 18:e4:dc:e1:22:bd:7c:0a:10:9a:a2:89:9d:a6:85:b6: server | fe 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: "uLJmNA80enz2tzLej2sdLyki0kYup5vewmJgbAkSH6jDrqASsfvdL9 server | ks01Yp4i1vVLhvjOt15G34aFr3mIzGekqFtMMZpLEp0b3qnEyJRnB1kYSP7cl server | VqXBQ3BOA4bqjz98GFjAhD3XI3D3WX6brUZEeLPZHypfWB9XCiu2N722tyzdk server | SheStdz4jGWAEvfUIsqVDXzbWGY0datCr8hulO9tdwPRB7SghcqaimsfjnC13 server | 9C1edcUm1y7Es" server | DNS name: "DTux3888wnNyDetXqpdcTFjPDT5ZGe9PCKOeBjMJmtkS6YmLSmq5yk server | CyY3kirOjxbb02iGQTgjeJhW0iKEHNMj6L6cuzJHyNjxVK7U1CSoik32tvyaX server | rdBRqFlUSk083pgwr2GoBg60ukasXZjtSViD9g71HjQiJ0g2liyx9NGVPRGop server | ysaoUDyrKHgKe08H4A0qRKM5aqoT4M1Wij2xupkiztcOzWwPREbBSF0NHbeoY server | RNfgYx5JiKmzu" server | DNS name: "Vzl7SfE9RsPwbYtuhmBMWNUVh6QV1UiycHsjbtvT34D7C1ieLFQUQR server | Icbo3Nxvv0Ad40AzdFJ7F7dZsrCxeMZIngDZpiGHZ7GFDyxjiyHAQEgLfxU5F server | TUxvkk1UGnKZ2DIIOay0041kgrVftJV6sUowbPfyy4klzJ53rJx142leDQMKK server | drZwrfO5rA0ylPjuCgIhhQmSXsYaouvany4dRPugj4dEhpeFpXmomqEVMwPRv server | 1YbT46N3tOEpe" server | DNS name: "dTZPzG5dIggTBBykbuWB3Dju5oWe48DorYAcjhEwOPJbdvxNKmMKst server | kLA3YlaZ1siK8TFd7JNYny9cRCUOUObQsSJNeslKV1iuSrv7bCcpZNlKbfSD6 server | LhleDIjCdgfa9Ec7QTCpaNvSlPYQSyHuKiIjPBNQziBr6ck4FNnmMLo2ext9i server | LnqHrpvicWzEvkVrdGGC8gh1uWrhAebSIPWR697fD29Gbns4mitqKkTSeKiOf server | 7Xf4U8zItE9jX" server | DNS name: "RZKJqUaY2ClozQbxX1SaGUIgcLUR104kDxPciMqkCF1ri2LqEgqNOa server | VJOXahGAk82vQ7COmu14ld7WVZlR4JZr0z9CIzjMtwuuT4kJK71gLAbmuXcT1 server | IbwRKfVgC09CCF5KuA8elp8xiORudrlS9auudrSqnFNncwSJbTVXOhXdRkzc4 server | WxI3rMbVBWnzLKq5kb1xvF3TZSjuHs455QR1oTK9hMXXKZSrVe9PchPKMj8eT server | IQ9jdLtQkO4pa" server | DNS name: "PQ2JoAxn8uozTRGLtfJ5NkSTDHAFGopKCjJ1E5KPBUjlOm5b9B7weF server | BW75sCpdcNWf6SaAclGafVmr1kLMPMlvBLgVk4IZRM3ZZdLDUJnn8hNub3mZJ server | EMVL8dShEUg6OyWxTZyUnaU19K5Ee8wUy6Be3uIK9o2GVl5mzMnbZrcvE0Lua server | oU3KYgCqZIGEu5kj7WA4m43uHFJ1kf8yqdpjTquGhwqsHcNAMWAuknEYmByEu server | U78auVUAA0lU0" server | DNS name: "VjDBXfnBPE2COpHRDoMdh1WpDopmZHedYsGR7iDPLtrUBpDC2uLYKL server | 7ffAa5COBo2imLbQLs2pcNq7onmqvfJ56Ggu7TbGDBV7DH4o8kQ9jWE8Dy5d8 server | 8Cl8dwmjvvkKPgPq5u5jjnrqPHU6BFx9lz9afn2j7VjgVGVgdeDEtIAYNhQcu server | uuW3eUeF0UylYLkMrgBgIYSQEzQ6zgobf3Dc2OjJb4aqlCUzPd2anzZSfPYMo server | rm85qN72YdLHY" server | DNS name: "hXeiTVa2g5QD8OEdplOUOx5XuxPZ2n3SJ07L69e8F7KO0DzR6jbEnP server | JAJgf3e2sly0cNSmcp5xxTgNm3nqsl2zDFbwFn1LtfGBHi56hsDRBKgAUttke server | ug5I9RICfWGJ54g6Fx72mGOdGEWSxh07SneJYaP3djp4cNN35hYOxEXUNI96g server | GmkCztEITdWGEd4Gcp3CIfZ8U8ogmLUdWLHKnpVwanbKBsGFf1g5qkLSjy4ti server | nRo30fBuQnxYC" server | DNS name: "aGh9bmr5M0Ytg41FtFZnwNPjnaymz76YHZ6tDUxLezuW1pNjUbwMUD server | qMBUcC7BsJPsirugrSXxZFrV2jpkPx4Ig65tEFpoc4fHxJHiX8wmoLnAv0yhr server | KhHa1CI3aTwBMDl8I0EmCLTMM9WMfHTubEt0S4cjH7ZyxqLx8J0pc5ABOOLkZ server | G3EbxbsCD2ihvwaPwVNnYGAaBYYxiVjFYesri4FXLC4cU3gcekOMv5TFQUy49 server | 0ezgf7qcpr7Ww" server | DNS name: "oYajSav4PLWMjmcqd4z9ljDR2P1DnpunnrudcN8GRZpl7DJL57w9ID server | QJ1w62OpbdnKBPZXQCejJDt3r1smZHCMGj4aGnm7WuXRwmPp3DeOtCWYUK6Vq server | bc0XgE8NIxB3rXM7rhIpkFDPsuQvQBXHCoPtIxpfP8r5Ox5ViodmjMoiiNTVk server | 8flGcrxgmWMioBcVB5sezBxCAnqBVQcAIeNzHEb50o5hsepb4hZr23xx7Efel server | owxjtHqjzbLTZ" server | DNS name: "g5arC0SpAGdSeUQvQEWhgc7r16iTMDXk933oxunA7fJpCJOb20wnwe server | NWmW3GAmOuxHNuKOUecKEFI4cdqwnwp0qHzD9AJFAfksSBxbXFRORrLFjBpep server | I9SIUs1AwCVFz6RP8Z8fJTNhObNoTP52nYHhfAEa8QKA0zd2XrYWXAOSOTmQq server | kXA3MALa7Dty20ADA0iDQJy7opmcLitVaoTxbrSeyQvbcbHx2J8R1PXCurl3S server | IxgiTdCViMgO5" server | DNS name: "r9Pp7tQZcsvkqJFv6kxkebovUFpKQG7BzyUWSiyZqn1mqFd6KK2b2w server | WdDOxqB2Mgev9Kr1EYhabi8RNlMnoctXlZCiQf2qeeHZV03FCg5wI2sPAwj8j server | 3DqXJOcUnCKlPSrS80WojPUcDRSa1nVGPfnDA2Iff8o1T479iYf78ceOQmv9v server | RNMmLOK8SPlBJcEUhyNcX1j8fXTrYDW0NnA9ZbNNBGnYfbWWYSQpay2OIaRCr server | fxi6WK3EeZTkF" server | DNS name: "B5YQYlOUkb9Q6udc9RnegUC3cUFgzwsQQkxtjKfs4uFmIUxABZyvg7 server | zxTOwWrHR7xGWw89RqR4r9RplAN2vmwstHTgA8cvJm4mLqjbkivceocMIeYvx server | cMrTLr3CuPth4zzuGtbrUKcX6HoyOnwBalsjika876RNQNBFyH8KXMrFjApnF server | YL4Wwgu5fYY1dC5ux2sW8fC84gatxZE9BbUBEHhX2o445DGKMCbdm1MCC8RK5 server | 9HP7ZSl5IWTLY" server | DNS name: "Sv4XjRx1F94L8HrdDa5UPJ60T27LeY0NfhpDRVg0gctwZHps4AkIrC server | GpBKa5Kxxr6dpGzIaOp7Ch2FEV71JNLdt4zZUk11CAeXyUeqKqirVieOyqzjY server | VLXaUVeOL8gdZbQrpYI9bBxaWsRpA7TiCOVpeT8Ara41KqNKwpLhYNVHPIKf9 server | Ze3qtbhQpVyWjDEzrR8ZNy6PpBGoODwWwDOgjf0m0o0zF0sBTXVlIRLwwqZz3 server | 9YN087uEeeW7t" server | DNS name: "QJJuJkrOOxQINxGGOzKctMKrtcFePodrpSPtUpOu2sE08QW3sEgNLH server | 5evOcIqOxPAkVxLgAGhzqhUC97p2OLFnh9O2blBiBtQuzm4zckTZjbLp5KAAv server | OSZxkRaZKcTuGWQNiYSqjBMzKvFRQDRSjKgE4StWNywxcIzJMsjEdsH5SdySr server | yUa8SU5rF7c0xkEcInPKXoHUrK6jaXSshVTALkgWYcOL26NGR4BoRkH97ObXt server | BjdgvjOFK62dS" server | DNS name: "OOhWXtYKCVN58k7F8uKK93zGFH3u2CvjFgcS0hX12ezVkawUggADqL server | No3urr1hE8IGtr3qa0iLmlXfJvMarFIzUrMwiXoXm3vLcOkdyKDfdGAgOzBnB server | KYAqPrbgVtxZoKOkMEmSZpm2cInc1z6hLIZAqZ2OYUL7FpFDX8BNF0mW0M0UL server | 8ouidwiDVjeIbPsbQR6VoFIVvdKzdbTiV9J7e3EgAitAe9fFZAYgqwBRSWwpX server | t4VVtkaocGQe2" server | DNS name: "NHavGOa8A2mrkWwWJiE4lErXspL6pXyOWv4SaDWVKoD7K4DZkl8XGE server | reBmpW1a3EAxcBOfeb4AGMsS7RsZN7ACiYktpkMJ23wZMUsttbrVx56g50cnq server | TAkITn7o1NlmQDfYmjkm3LBC6ziCwAnPgHZWBvD2mJmtUlCyNypqUghsOJZor server | rHrLKC0lFW68XEthiUKyBm6LtuDz2fxVcX23047gw4Vyz7e8PhVjaVhVC0FQq server | F2vrkAmlFJ52Z" server | DNS name: "fIAQpJqIaFBBoMzohX7tkFunlDza8kNHzWiImUR0XZmR3CRiyQgRAU server | 87iAmILAVsugHO9bgTyyFu8tm49zwIeY8C2SGzGIsCTh3ocbKFl2Gt8berjKF server | lcAdwq9W1WCwLO1YLpMWg878oo7IdP8lLFpBZdEviEhQxTTSg7fs2ClsnE3fN server | 2z5Dln5GeYJGJ1Gwv73TWphT5dQwcit1UF360Syx1RNvDe1InGqiDVR2Tjg3R server | DoFeFqajGrjkt" server | DNS name: "pe673IPCipaZEn6wX5qhlGT89WPhhyY9zf5n8S5qms0lQzFA9OhVPZ server | qJolOynop87I9ohbZ2VtCFOQUrAXVyg7VxIdYZvrMVjCdmuVpMJ87ZFAqy02p server | 95s9HBbPD7RLPz685f8Dk7XCXGA9SmvEmAek7o61nusBDHj3NrBxyDXddN2VD server | tzEDNScDQaCgdpoIlzZ1wAHYirNBXFtYzh2JnHppzSI5IiS7DMtThcu1gTS3k server | 2QgozREnskWOn" server | DNS name: "elEeFdNb2cZUYwBak0eyrkkEadS9sXdj2sBTatfMWw6XtUvCPhkAE0 server | 6f5gCJxIyeUoVBd4ldJQl5QbkG6VfrEbLhQlu1if2RzIDhc0dbfUTlcLhMWWV server | ebvneRrbxWpxvxfzEAyg0CrBFNRBW1TP1ewKuXkNFjxjFSXrM67SQpSlycH5d server | qe0TGQuIlXz7ELNsHC5b8X3h2UTnP9FdD71Yoj5WDijBqnI2R4zz0YysrynEn server | 1LZ6J1XvAAQWs" server | server | Name: Certificate Subject Key ID server | Data: server | ce:a7:f1:79:8f:ba:e6:b6:20:36:50:3d:49:a0:ca:5a: server | e5:4d:47:7c server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 7a:5c:8b:a7:19:bf:fe:05:77:b6:3b:98:ca:f2:06:2a: server | b2:d9:35:49 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:21:00:c3:86:9c:32:a2:1a:52:ea:c5:01:51: server | ff:c3:53:34:53:28:f2:58:74:48:38:14:2e:f2:97:09: server | df:e5:6b:a1:fa:02:20:56:45:12:79:86:a0:26:8d:d7: server | 37:0f:79:aa:bb:4a:fe:cb:4a:b8:b2:7c:16:e4:31:b9: server | e3:5d:a3:bb:0f:2a:fb server | Fingerprint (SHA-256): server | 1B:97:1B:78:78:9E:F4:A8:5E:D9:0F:37:09:F3:A9:56:A4:75:40:A1:E6:37:D2:82:6E:DE:2E:03:ED:89:94:4E server | Fingerprint (SHA1): server | B6:8D:2E:0C:A7:6D:78:A0:BA:D2:31:9D:AE:51:1F:41:62:7A:51:47 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 sim | server:443 is available after 1.045820422s server | 0.772 DEBUG [Server] Unsupported version: 57414954 server | 0.772 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:42850 IpTos(Cs0, NotEct) len 15 server | 0.772 DEBUG [Server] Unsupported version: 57414954 server | 0.772 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:42850 IpTos(Cs0, NotEct) len 15 server | 0.772 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.772 DEBUG [Server] Unsupported version: 57414954 server | 0.772 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:42850 IpTos(Cs0, NotEct) len 15 server | 0.772 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)tcpdump: , snapshot length 262144 bytes sim | listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | Starting picoquic client for test: transfer client | Starting picoquic client ... client | Parsing server name from first request: https://server4:443/jwxecdtvwr client | Server set to: server4 client | Requests: https://server4:443/jwxecdtvwr client | parsing as client | Starting Picoquic (v1.1.26.1) connection to server = server4, port = 443 client | No token file present. Will create one as . client | Testing scenario: <-:/jwxecdtvwr;> client | Max stream id bidir remote before start = 0 (0) client | Starting client connection. Version = 1, I-CID: 6507078cec31f7be client | Max stream id bidir remote after start = -4 (0) client | Waiting for packets. server | 0.978 DEBUG [Server] Handle initial server | 0.978 INFO AddressValidation: no token; accepting server | 0.978 INFO [Server] Accept connection CID [8]: 6507078cec31f7be server | 0.978 DEBUG Overwrite initial version Version1 ==> Version1 server | 0.982 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 0.982 INFO [Server ...] Received valid Initial packet with scid CID [8]: 857662ca323a8cc0 dcid CID [8]: 6507078cec31f7be server | 0.982 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=6507078cec31f7be server | 0.982 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 0.982 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 0.982 DEBUG [Server 6507078cec31f7be] State change from Init -> WaitInitial server | 0.982 DEBUG [Server 6507078cec31f7be] pn=98892 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 1252 server | -> RX Ping server | -> RX Crypto { offset: 0, len: 283 } server | -> RX Padding { len: 918 } server | 0.982 DEBUG unmark 0-length range at 0 server | 0.982 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 443, tv_nsec: 254161005 } server | 0.982 DEBUG Read Ok(283) bytes server | 0.982 DEBUG 0-RTT: no token, no 0-RTT server | 0.983 DEBUG Read secret available for Handshake: SymKey [32]: 2a1cb93b6c337d38d167953fa56498e256bddd92824cc1788ce9b476413b731b server | 0.983 DEBUG Write secret available for Handshake: SymKey [32]: d21e8beb5121c6a2b71a6c5d0e7c18acd4ea8c8df0b9b99627c1cb90feb982b3 server | 0.983 DEBUG Writing transport parameters, msg=8 server | 0.983 DEBUG Read secret available for ApplicationData: SymKey [32]: f109de6fb361dce3ce4ebd03611f4b02889559635a96865b78cf74ad7211be24 server | 0.983 DEBUG Write secret available for ApplicationData: SymKey [32]: 44cfdb751072e535a041eecc8b236f68d774b846f553de8871b486af63a09403 server | 0.983 DEBUG [Agent 0x56313ad9b1d0] state -> InProgress server | 0.983 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 0.983 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 0.983 DEBUG [Crypto] Handshake keys installed server | 0.983 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 0.984 DEBUG [Crypto] Application write key installed server | 0.984 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 443, tv_nsec: 254161005 } server | 0.984 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:34257] Make permanent server | 0.984 DEBUG [unv-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] set as primary path server | 0.984 DEBUG [Server 6507078cec31f7be] State change from WaitInitial -> Handshaking server | 0.984 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.984 DEBUG Building Initial dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG CRYPTO for in offset=0, len=123 server | 0.984 DEBUG [Server 6507078cec31f7be] pn=0 type=Initial pri-unv-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 183 server | TX -> Ack { largest_acknowledged: 98892, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 123 } server | 0.984 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG CRYPTO for hs offset=0, len=1001 server | 0.984 DEBUG [Server 6507078cec31f7be] pn=0 type=Handshake pri-unv-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 0, len: 1001 } server | 0.984 DEBUG packet_sent this=0x56313ad9ab40, pn=0, ps=1049 server | 0.984 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 0.984 DEBUG packet_sent this=0x56313ad9ab40, pn=0, ps=183 server | 0.984 DEBUG ECN probing: sent 1 probes server | 0.984 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.984 DEBUG Building Initial dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG CRYPTO for hs offset=1001, len=1183 server | 0.984 DEBUG [Server 6507078cec31f7be] pn=1 type=Handshake pri-unv-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 1001, len: 1183 } server | 0.984 DEBUG packet_sent this=0x56313ad9ab40, pn=1, ps=1232 server | 0.984 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 0.984 DEBUG ECN probing: sent 2 probes server | 0.984 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.984 DEBUG Building Initial dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG CRYPTO for hs offset=2184, len=1183 server | 0.984 DEBUG [Server 6507078cec31f7be] pn=2 type=Handshake pri-unv-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 2184, len: 1183 } server | 0.984 DEBUG packet_sent this=0x56313ad9ab40, pn=2, ps=1232 server | 0.984 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 0.984 DEBUG ECN probing: sent 3 probes server | 0.984 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.984 DEBUG Building Initial dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 0.984 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.984 DEBUG [Server 6507078cec31f7be] delay duration 29.99400959s server | 0.984 DEBUG Setting timeout of 29.99400959s server | 0.984 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.984 DEBUG Building Initial dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 0.984 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 0.984 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.984 DEBUG [Server 6507078cec31f7be] delay duration 29.99397744s server | 0.984 DEBUG Setting timeout of 29.99397744s client | Client port (AF=2): 53637. sim | Dropping packet 2 (55 bytes) from 193.167.0.100 sim | Dropping packet 3 (55 bytes) from 193.167.0.100 sim | Dropping packet 4 (55 bytes) from 193.167.0.100 sim | Dropping packet 5 (57 bytes) from 193.167.0.100 sim | Dropping packet 6 (59 bytes) from 193.167.0.100 sim | Dropping packet 7 (59 bytes) from 193.167.0.100 server | 2.648 DEBUG [Server 6507078cec31f7be] Drop packet number space in server | 2.648 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 2.648 INFO [pri-unv-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] discarding a packet without an RTT estimate; guessing RTT=1.670376268s server | 2.648 DEBUG [Server 6507078cec31f7be] pn=6 type=Handshake pri-unv-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 59 server | -> RX Ping server | -> RX Ack { largest_acknowledged: 2, ack_delay: 203866, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | 2.648 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 444, tv_nsec: 924537273 } server | 2.648 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[0..=2] server | 2.648 DEBUG [LossRecovery] ACK for Handshake - largest_acked=2 server | 2.648 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=3513 server | 2.648 DEBUG Acked crypto frame space=hs offset=2184 length=1183 server | 2.648 DEBUG Acked crypto frame space=hs offset=1001 length=1183 server | 2.648 DEBUG Acked crypto frame space=hs offset=0 length=1001 server | 2.648 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 924537273 } server | 2.648 DEBUG [pri-unv-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 444, tv_nsec: 924537273 } server | 2.648 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.648 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.648 DEBUG CRYPTO for hs offset=3367, len=1175 server | 2.648 DEBUG [Server 6507078cec31f7be] pn=3 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 3367, len: 1175 } server | 2.648 DEBUG packet_sent this=0x56313ad9ab40, pn=3, ps=1232 server | 2.648 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.648 DEBUG ECN probing: sent 4 probes server | 2.648 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.648 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.648 DEBUG CRYPTO for hs offset=4542, len=1183 server | 2.649 DEBUG [Server 6507078cec31f7be] pn=4 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 4542, len: 1183 } server | 2.649 DEBUG packet_sent this=0x56313ad9ab40, pn=4, ps=1232 server | 2.649 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.649 DEBUG ECN probing: sent 5 probes server | 2.649 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.649 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.649 DEBUG CRYPTO for hs offset=5725, len=1183 server | 2.649 DEBUG [Server 6507078cec31f7be] pn=5 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 5725, len: 1183 } server | 2.649 DEBUG packet_sent this=0x56313ad9ab40, pn=5, ps=1232 server | 2.649 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.649 DEBUG ECN probing: sent 6 probes server | 2.649 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.649 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.649 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.649 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.649 DEBUG [Server 6507078cec31f7be] delay duration 83.230809ms server | 2.649 DEBUG Setting timeout of 83.230809ms server | 2.649 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.649 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.649 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.649 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.649 DEBUG [Server 6507078cec31f7be] delay duration 83.208147ms server | 2.649 DEBUG Setting timeout of 83.208147ms server | 2.680 DEBUG [Server 6507078cec31f7be] pn=7 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 56 server | -> RX Ping server | -> RX Ack { largest_acknowledged: 3, ack_delay: 9, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | 2.680 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[2..=3] server | 2.680 DEBUG [LossRecovery] ACK for Handshake - largest_acked=3 server | 2.680 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=2464, cwnd=12320, state=SlowStart, new_acked=1232 server | 2.680 DEBUG Acked crypto frame space=hs offset=3367 length=1175 server | 2.680 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [Server 6507078cec31f7be] pn=8 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 56 server | -> RX Ping server | -> RX Ack { largest_acknowledged: 3, ack_delay: 9, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | 2.680 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[2..=3] server | 2.680 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [Server 6507078cec31f7be] pn=9 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 56 server | -> RX Ping server | -> RX Ack { largest_acknowledged: 3, ack_delay: 9, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | 2.680 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[2..=3] server | 2.680 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 444, tv_nsec: 956552231 } server | 2.680 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.680 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.680 DEBUG [Server 6507078cec31f7be] pn=6 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 52 server | TX -> Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 4, Ect0: 0, Ce: 0})) } server | 2.680 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.680 DEBUG ECN probing: sent 7 probes server | 2.680 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.680 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.680 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.680 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.680 DEBUG [Server 6507078cec31f7be] delay duration 44.278718ms server | 2.680 DEBUG Setting timeout of 44.278718ms server | 2.680 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.680 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.680 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.680 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.680 DEBUG [Server 6507078cec31f7be] delay duration 44.251607ms server | 2.680 DEBUG Setting timeout of 44.251607ms server | 2.681 DEBUG [Server 6507078cec31f7be] pn=10 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | 2.681 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[2..=4] server | 2.681 DEBUG [LossRecovery] ACK for Handshake - largest_acked=4 server | 2.681 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232 server | 2.681 DEBUG Acked crypto frame space=hs offset=4542 length=1183 server | 2.681 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 444, tv_nsec: 957274888 } server | 2.681 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.681 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.681 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.681 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.681 DEBUG [Server 6507078cec31f7be] delay duration 38.312763ms server | 2.681 DEBUG Setting timeout of 38.312763ms server | 2.681 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.681 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.681 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.681 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.681 DEBUG [Server 6507078cec31f7be] delay duration 38.213357ms server | 2.681 DEBUG Setting timeout of 38.213357ms server | 2.682 DEBUG [Server 6507078cec31f7be] pn=11 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } server | 2.682 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[2..=5] server | 2.682 DEBUG [LossRecovery] ACK for Handshake - largest_acked=5 server | 2.682 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 2.682 DEBUG Acked crypto frame space=hs offset=5725 length=1183 server | 2.682 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 444, tv_nsec: 958295163 } server | 2.682 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.682 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.682 DEBUG CRYPTO for hs offset=6908, len=1183 server | 2.682 DEBUG [Server 6507078cec31f7be] pn=7 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 6908, len: 1183 } server | 2.682 DEBUG packet_sent this=0x56313ad9ab40, pn=7, ps=1232 server | 2.682 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.682 DEBUG ECN probing: sent 8 probes server | 2.682 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.682 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.682 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.682 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.682 DEBUG [Server 6507078cec31f7be] delay duration 56.368722ms server | 2.682 DEBUG Setting timeout of 56.368722ms server | 2.682 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.682 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.682 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.682 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.682 DEBUG [Server 6507078cec31f7be] delay duration 56.343495ms server | 2.682 DEBUG Setting timeout of 56.343495ms server | 2.714 DEBUG [Server 6507078cec31f7be] pn=12 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 3, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } server | 2.714 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[4..=7] server | 2.714 DEBUG [LossRecovery] ACK for Handshake - largest_acked=7 server | 2.714 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 2.714 DEBUG Acked crypto frame space=hs offset=6908 length=1183 server | 2.714 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 444, tv_nsec: 989870980 } server | 2.714 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.714 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.714 DEBUG CRYPTO for hs offset=8091, len=1183 server | 2.714 DEBUG [Server 6507078cec31f7be] pn=8 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 8091, len: 1183 } server | 2.714 DEBUG packet_sent this=0x56313ad9ab40, pn=8, ps=1232 server | 2.714 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.714 DEBUG ECN probing: sent 9 probes server | 2.714 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.714 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.714 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.714 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.714 DEBUG [Server 6507078cec31f7be] delay duration 49.477694ms server | 2.714 DEBUG Setting timeout of 49.477694ms server | 2.714 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.714 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.714 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.714 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.714 DEBUG [Server 6507078cec31f7be] delay duration 49.422802ms server | 2.714 DEBUG Setting timeout of 49.422802ms server | 2.745 DEBUG [Server 6507078cec31f7be] pn=13 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 8, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | 2.745 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[4..=8] server | 2.745 DEBUG [LossRecovery] ACK for Handshake - largest_acked=8 server | 2.745 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 2.745 DEBUG Acked crypto frame space=hs offset=8091 length=1183 server | 2.745 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 445, tv_nsec: 21543319 } server | 2.745 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.745 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.745 DEBUG CRYPTO for hs offset=9274, len=695 server | 2.745 DEBUG [Server 6507078cec31f7be] pn=9 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 744 server | TX -> Crypto { offset: 9274, len: 695 } server | 2.745 DEBUG packet_sent this=0x56313ad9ab40, pn=9, ps=744 server | 2.745 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.745 DEBUG ECN probing: sent 10 probes server | 2.745 DEBUG ECN probing concluded with 10 probes sent server | 2.745 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.745 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.745 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.745 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.745 DEBUG [Server 6507078cec31f7be] delay duration 38.169858ms server | 2.745 DEBUG Setting timeout of 38.169858ms server | 2.745 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.745 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.745 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.745 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.745 DEBUG [Server 6507078cec31f7be] delay duration 38.137568ms server | 2.745 DEBUG Setting timeout of 38.137568ms client | Negotiated ALPN: hq-interop client | Almost ready! client | client | Connection established. Version = 1, I-CID: 6507078cec31f7be, verified: 1 client | Opening stream 0 to GET /jwxecdtvwr server | 2.777 DEBUG [Server 6507078cec31f7be] pn=14 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 94 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 13, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) } server | -> RX Crypto { offset: 0, len: 36 } server | 2.777 DEBUG [Server 6507078cec31f7be] Rx ACK space=hs, ranges=[4..=9] server | 2.777 DEBUG [LossRecovery] ACK for Handshake - largest_acked=9 server | 2.777 INFO ECN validation succeeded, path is capable server | 2.777 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=744 server | 2.777 DEBUG Acked crypto frame space=hs offset=9274 length=695 server | 2.777 DEBUG Read Ok(36) bytes server | 2.777 DEBUG [Agent 0x56313ad9b1d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 23, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 2.777 DEBUG [Server 6507078cec31f7be] TLS connection complete server | 2.777 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 445, tv_nsec: 53226256 } server | 2.777 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 2.777 DEBUG [Crypto] application read keys installed server | 2.777 DEBUG [Server 6507078cec31f7be] State change from Handshaking -> Connected server | 2.777 DEBUG [Server 6507078cec31f7be] State change from Connected -> Confirmed server | 2.777 DEBUG PMTUD started with probe size 1380 server | 2.777 INFO [Server 6507078cec31f7be] Connection established server | 2.777 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 445, tv_nsec: 53226256 } server | 2.777 DEBUG [Server 6507078cec31f7be] pn=0 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 311 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [213, 84, 149, 249, 254, 29, 71, 222], stateless_reset_token: [226, 153, 253, 191, 110, 127, 126, 216, 180, 78, 144, 210, 100, 151, 43, 126] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [163, 41, 140, 184, 233, 38, 91, 92], stateless_reset_token: [49, 74, 187, 7, 108, 153, 166, 135, 239, 249, 89, 206, 249, 236, 91, 43] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [15, 244, 101, 144, 83, 160, 178, 198], stateless_reset_token: [123, 188, 250, 2, 3, 184, 240, 143, 159, 125, 145, 61, 131, 109, 234, 150] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [29, 246, 137, 103, 240, 212, 115, 61], stateless_reset_token: [84, 43, 205, 141, 240, 59, 93, 139, 201, 39, 143, 234, 143, 212, 7, 143] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [123, 180, 65, 13, 193, 182, 118, 37], stateless_reset_token: [132, 126, 39, 36, 107, 241, 130, 89, 203, 43, 66, 131, 93, 5, 54, 144] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [227, 81, 63, 214, 167, 121, 164, 204], stateless_reset_token: [203, 175, 5, 228, 245, 233, 53, 248, 124, 135, 220, 110, 75, 204, 183, 143] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [138, 50, 14, 108, 57, 246, 157, 203], stateless_reset_token: [75, 73, 23, 222, 90, 192, 213, 245, 23, 160, 61, 185, 18, 184, 100, 112] } server | -> RX Padding { len: 87 } server | 2.777 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 445, tv_nsec: 73226256 } server | 2.777 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.777 DEBUG Building Handshake dcid Some(CID [8]: 857662ca323a8cc0) scid Some(CID [10]: d6a1c82857c32ff88577) server | 2.777 DEBUG [Server 6507078cec31f7be] pn=10 type=Handshake pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 52 server | TX -> Ack { largest_acknowledged: 14, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 9, Ect0: 0, Ce: 0})) } server | 2.777 DEBUG [Server 6507078cec31f7be] Drop packet number space hs server | 2.777 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 2.777 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.777 DEBUG [Server 6507078cec31f7be] pn=0 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [26, 136, 108, 80, 153, 129, 45, 82, 67, 140], stateless_reset_token: [137, 255, 226, 179, 31, 64, 211, 156, 38, 49, 163, 185, 158, 238, 134, 139] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [174, 222, 46, 22, 80, 53, 107, 108, 49, 206], stateless_reset_token: [94, 255, 26, 135, 76, 75, 229, 1, 100, 18, 75, 184, 119, 236, 102, 62] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [237, 17, 108, 4, 228, 171, 222, 47, 56, 203], stateless_reset_token: [234, 41, 15, 8, 71, 237, 41, 181, 85, 27, 128, 129, 186, 227, 244, 148] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [227, 2, 168, 88, 28, 148, 72, 237, 202, 170], stateless_reset_token: [30, 54, 210, 182, 47, 251, 56, 134, 60, 215, 141, 45, 160, 101, 78, 60] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [46, 194, 190, 174, 208, 130, 229, 169, 125, 37], stateless_reset_token: [154, 184, 77, 136, 120, 92, 216, 229, 2, 248, 18, 168, 195, 7, 170, 3] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [167, 35, 205, 80, 37, 5, 7, 27, 63, 8], stateless_reset_token: [174, 167, 63, 137, 13, 62, 160, 184, 100, 63, 93, 161, 206, 157, 145, 2] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [227, 83, 92, 64, 55, 176, 246, 209, 209, 186], stateless_reset_token: [182, 220, 142, 17, 161, 70, 165, 96, 204, 138, 204, 29, 111, 130, 222, 187] } server | TX -> Padding { len: 1043 } server | 2.777 DEBUG packet_sent this=0x56313ad9ab40, pn=0, ps=1280 server | 2.777 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.777 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.777 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.777 DEBUG [Server 6507078cec31f7be] delay duration 19.498534ms server | 2.777 DEBUG Setting timeout of 19.498534ms server | 2.777 DEBUG [Server 6507078cec31f7be] pn=1 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Padding { len: 7 } server | 2.777 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 445, tv_nsec: 53751206 } server | 2.777 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.777 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.777 DEBUG [Server 6507078cec31f7be] pn=1 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 34 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 0, Ce: 0})) } server | 2.778 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.778 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.778 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.778 DEBUG [Server 6507078cec31f7be] delay duration 38.279472ms server | 2.778 DEBUG Setting timeout of 38.279472ms server | 2.778 DEBUG [Server 6507078cec31f7be] send session ticket 406300086507078cec31f7be0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0ad6a1c82857c32ff88577110c000000010a9a4a9a000000016ab200c0000000ff02de1a0243e8200100686921 server | 2.778 DEBUG Path = 'jwxecdtvwr' server | 2.779 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.779 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.779 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.779 DEBUG [Server 6507078cec31f7be] delay duration 37.23931ms server | 2.779 DEBUG Setting timeout of 37.23931ms server | 2.813 DEBUG [Server 6507078cec31f7be] pn=2 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 477, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } server | -> RX Padding { len: 18 } server | 2.813 DEBUG [Server 6507078cec31f7be] Rx ACK space=ap, ranges=[0..=1] server | 2.813 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 2.813 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1280 server | 2.813 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.813 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.813 DEBUG Sending PMTUD probe of size 1380, count 1 server | 2.813 DEBUG [Server 6507078cec31f7be] pn=2 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 0, len: >>1303, fin: false } server | 2.813 DEBUG packet_sent this=0x56313ad9ab40, pn=2, ps=1332 server | 2.813 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.813 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.813 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.813 DEBUG [Server 6507078cec31f7be] delay duration 33.582282ms server | 2.813 DEBUG Setting timeout of 33.582282ms server | 2.813 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.813 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.813 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.813 DEBUG [Server 6507078cec31f7be] delay duration 33.546355ms server | 2.813 DEBUG Setting timeout of 33.546355ms server | 2.848 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.848 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.848 DEBUG [Server 6507078cec31f7be] pn=3 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1303, len: >>1202, fin: false } server | 2.848 DEBUG packet_sent this=0x56313ad9ab40, pn=3, ps=1232 server | 2.849 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.849 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.849 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.849 DEBUG [Server 6507078cec31f7be] delay duration 32.044233ms server | 2.849 DEBUG Setting timeout of 32.044233ms server | 2.849 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.849 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.849 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.849 DEBUG [Server 6507078cec31f7be] delay duration 32.016291ms server | 2.849 DEBUG Setting timeout of 32.016291ms server | 2.849 DEBUG [Server 6507078cec31f7be] pn=3 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 491, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | -> RX Padding { len: 18 } server | 2.849 DEBUG [Server 6507078cec31f7be] Rx ACK space=ap, ranges=[0..=2] server | 2.849 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 2.849 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1332 server | 2.849 DEBUG PMTUD probe of size 1380 succeeded server | 2.849 DEBUG PMTUD started with probe size 1420 server | 2.849 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 2.849 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.849 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.849 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.849 DEBUG [Server 6507078cec31f7be] delay duration 30.508161ms server | 2.849 DEBUG Setting timeout of 30.508161ms server | 2.849 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.849 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.849 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.849 DEBUG [Server 6507078cec31f7be] delay duration 30.409176ms server | 2.849 DEBUG Setting timeout of 30.409176ms server | 2.880 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 2.880 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.880 DEBUG Sending PMTUD probe of size 1420, count 1 server | 2.880 DEBUG [Server 6507078cec31f7be] pn=4 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 2505, len: >>1341, fin: false } server | 2.880 DEBUG packet_sent this=0x56313ad9ab40, pn=4, ps=1372 server | 2.880 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.880 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.880 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.880 DEBUG [Server 6507078cec31f7be] delay duration 32.063033ms server | 2.880 DEBUG Setting timeout of 32.063033ms server | 2.880 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.880 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.880 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.880 DEBUG [Server 6507078cec31f7be] delay duration 32.035281ms server | 2.880 DEBUG Setting timeout of 32.035281ms server | 2.884 DEBUG [Server 6507078cec31f7be] pn=4 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 491, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | -> RX Padding { len: 18 } server | 2.884 DEBUG [Server 6507078cec31f7be] Rx ACK space=ap, ranges=[0..=3] server | 2.884 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3 server | 2.884 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=1372, cwnd=12320, state=SlowStart, new_acked=1232 server | 2.884 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.884 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.884 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.884 DEBUG [Server 6507078cec31f7be] delay duration 24.285124ms server | 2.884 DEBUG Setting timeout of 24.285124ms server | 2.884 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.884 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.884 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.884 DEBUG [Server 6507078cec31f7be] delay duration 24.219491ms server | 2.884 DEBUG Setting timeout of 24.219491ms server | 2.909 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 2.909 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.909 DEBUG CRYPTO for ap offset=0, len=23 server | 2.909 DEBUG [Server 6507078cec31f7be] pn=5 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1332 server | TX -> Stream { stream_id: 0, offset: 3846, len: 1274, fin: true } server | TX -> Crypto { offset: 0, len: 23 } server | 2.909 DEBUG packet_sent this=0x56313ad9ab40, pn=5, ps=1332 server | 2.909 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.909 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.909 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.909 DEBUG [Server 6507078cec31f7be] delay duration 27.418318ms server | 2.909 DEBUG Setting timeout of 27.418318ms server | 2.909 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.909 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.909 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.909 DEBUG [Server 6507078cec31f7be] delay duration 27.397108ms server | 2.909 DEBUG Setting timeout of 27.397108ms server | 2.915 DEBUG [Server 6507078cec31f7be] pn=5 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 491, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | -> RX Padding { len: 18 } server | 2.915 DEBUG [Server 6507078cec31f7be] Rx ACK space=ap, ranges=[0..=4] server | 2.915 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 2.915 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=1332, cwnd=12320, state=SlowStart, new_acked=1372 server | 2.915 DEBUG PMTUD probe of size 1420 succeeded server | 2.915 DEBUG PMTUD started with probe size 1470 server | 2.915 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 2.915 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.915 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.915 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.915 DEBUG [Server 6507078cec31f7be] delay duration 18.732205ms server | 2.915 DEBUG Setting timeout of 18.732205ms server | 2.915 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.915 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.915 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.915 DEBUG [Server 6507078cec31f7be] delay duration 18.6705ms server | 2.915 DEBUG Setting timeout of 18.6705ms client | Stream 0 ended after 5120 bytes client | All done, Closing the connection. server | 2.935 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 2.935 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.935 DEBUG Sending PMTUD probe of size 1470, count 1 server | 2.935 DEBUG CRYPTO for ap offset=23, len=282 server | 2.935 DEBUG [Server 6507078cec31f7be] pn=6 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 1422 server | TX -> Ping server | TX -> Crypto { offset: 23, len: 282 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 233, 128, 82, 203, 101, 201, 104, 52, 77, 156, 207, 128, 41, 58, 117, 118, 91, 51, 234, 85, 48, 196, 56, 212, 185, 233, 159, 187, 74, 118, 29, 214, 81, 252, 189, 57] } server | TX -> Padding { len: 1064 } server | 2.935 DEBUG packet_sent this=0x56313ad9ab40, pn=6, ps=1422 server | 2.935 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.935 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.935 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.935 DEBUG [Server 6507078cec31f7be] delay duration 25.683549ms server | 2.935 DEBUG Setting timeout of 25.683549ms server | 2.935 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.935 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.935 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.935 DEBUG [Server 6507078cec31f7be] delay duration 25.656839ms server | 2.935 DEBUG Setting timeout of 25.656839ms server | 2.941 DEBUG [Server 6507078cec31f7be] pn=6 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect1) len 39 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 10, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" } server | 2.941 DEBUG [Server 6507078cec31f7be] Rx ACK space=ap, ranges=[0..=5] server | 2.941 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5 server | 2.941 DEBUG on_packets_acked this=0x56313ad9ab40, limited=1, bytes_in_flight=1422, cwnd=12320, state=SlowStart, new_acked=1332 server | 2.941 DEBUG Acked crypto frame space=ap offset=0 length=23 server | 2.941 INFO [Server 6507078cec31f7be] ConnectionClose received. Error code: Application(0) frame type 0 reason server | 2.941 DEBUG [Server 6507078cec31f7be] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 454, tv_nsec: 179002390 } } server | 2.941 DEBUG [pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257] Path validated Instant { tv_sec: 445, tv_nsec: 217058299 } server | 2.941 DEBUG [Server 6507078cec31f7be] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.941 DEBUG Building Short dcid Some(CID [8]: 857662ca323a8cc0) server | 2.941 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 445, tv_nsec: 217058299 } server | 2.941 DEBUG [Server 6507078cec31f7be] pn=7 type=Short pri-path:857662ca323a8cc0 [::]:443->[::ffff:193.167.0.100]:34257 IpTos(Cs0, Ect0) len 38 server | TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 7, Ect0: 0, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 2.941 DEBUG Setting timeout of 8.961727695s server | 2.941 DEBUG Setting timeout of 8.961713478s client | Received a request to close the connection. client | The connection is closed! client | Address Discovery mode: 0 / 0 (0:0) client | Quic Bit was greased by the client. client | Quic Bit was greased by the server. client | ECN was received (ect0: 7, ect1: 0, ce: 0). client | ECN was acknowledged (ect0: 0, ect1: 2, ce: 0). client | Received 5120 bytes in 2.001739 seconds, 0.020462 Mbps. client | Sent 17 bytes in 2.001739 seconds, 0.000068 Mbps. client | max_data_local: 1048576 client | max_stream_data_local: 2097152 client | max_data_remote: 4611686018427387903 client | max_stream_data_remote: 1048576 client | ack_delay_remote: 1000 ... 3862 client | max_ack_gap_remote: 2 client | ack_delay_local: 25000 ... 25000 client | max_ack_gap_local: 2 client | max_mtu_sent: 1252 client | max_mtu_received: 1422 client | System call duration max: 6 client | System call duration smoothed: 0 client | System call duration deviation: 0 client | Client exit with code = 0 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 08:46:20,856 Using the client's key log file. 2025-04-30 08:46:20,868 2025-04-30 08:46:20,869 Using the client's key log file. 2025-04-30 08:46:20,869 Using the client's key log file. 2025-04-30 08:46:20,879 2025-04-30 08:46:20,879 Using the client's key log file. 2025-04-30 08:46:20,879 Using the client's key log file. 2025-04-30 08:46:21,523 Check of downloaded files succeeded. 2025-04-30 08:46:21,844 Server sent 9969 bytes in Handshake CRYPTO frames. 2025-04-30 08:46:22,202 Received a 1252 byte Initial packet from the client. Amplification limit: 3756 2025-04-30 08:46:22,202 Received a 1232 byte Handshake packet from the server. Total: 1232 2025-04-30 08:46:22,202 Received a 1232 byte Handshake packet from the server. Total: 2464 2025-04-30 08:46:22,202 Received a 1232 byte Handshake packet from the server. Total: 3696