2025-04-30 12:26:47,834 Generated random file: elpbvdavis of size: 5120 2025-04-30 12:26:47,834 Requests: https://server4:443/elpbvdavis 2025-04-30 12:26:48,211 2025-04-30 12:26:48,211 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_des7yhkn/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_nwng6lif/ DOWNLOADS=/tmp/download_4h78iudf/ SERVER_LOGS=/tmp/logs_server_2tpdmex4 CLIENT_LOGS=/tmp/logs_client_152exai4 SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=litespeedtech/lsquic-qir:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/elpbvdavis" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 12:27:02,521 Container sim Recreate Container sim Recreated Container server Recreate Container client Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... client | Setting up routes... 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 | 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 | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Endpoint's IPv6 address is fd00:cafe:cafe::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.w69If4i8aQ 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.w69If4i8aQ server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.w69If4i8aQ -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 | 08:54:60:e8:2b:b9:16:de:e6:78:a5:48:f1:7a:b8:26: server | b7:c1:16:f7 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner intermediate 8" server | Validity: server | Not Before: Wed Apr 30 12:26:48 2025 server | Not After : Sat May 10 12:26:48 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:77:5d:be:10:36:7c:5e:c7:8f:51:64:7c:36:65:f1: server | 79:d6:bc:7b:ad:4d:7f:0f:17:33:a1:4a:c0:38:df:09: server | 74:e2:49:43:d3:0f:7e:92:05:3a:c2:9f:df:b7:d0:67: server | 7b:91:da:ca:93:bb:81:c1:2f:e5:11:81:2d:3d:ca:d5: server | 59 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: "VFsxdIARV8fapsUpMaRlBcsJwhYhLEGUZseuf9Mx70ZWHxw0PMNR5G server | 6hPeDzLXSJPA5hO5AFPnJ2pnR5bURp45wzsf5BGpdVuA5TOlswP6hZmPc5Gz1 server | WhkhHTeWL3HXT1IRxuzq0ufv9ZIHgiYVBvNmU6vTl6cvPjvYuUI3Qr7CFY4Fj server | oBHuLj6n7NY2o2HKiqrKBDs96waZo2y3whRHSkjWPPbqF97N55bW4Fh9GESq3 server | 5ujBf7xSu2mrQ" server | DNS name: "mIpSBw99btFBpZPHndM8G429w1vX8tGpAhV0Uqii6QWEbnmWTKb463 server | o5x6ow0xtKdTRjPuKNwuxWrZ8DEsJ98FadTgdUPEP5gkSK1FZWR0Cdo26ef24 server | j03JNIhhtNhF21jmGYwRl10mEkM2daOLHtNz499LozPnmsiSZEkMbLScqgaU3 server | 8mrDT6F44fkAaeKLhxE3Ug6v9LcOBGMjDmverT7OF50JrJGq2gC9Lc9z126Ec server | bDrOQtbgsnr8H" server | DNS name: "AMNlcEebVsJQCHLhZSCYZwZj7CVl4SkueFIbMDVdylRKFHDqg39BPs server | uLF6HdCskMZ38oKijOACGfa6R518mCyEWKKbGgkXinsAeEujyeQWmeEYROzet server | rdtTOffaOSXXwT9U9tpLqqRveooF4oseSGz0MTRXqA3Ib0gUzLQecsmwIQ2OI server | OkCWN4n4scfGnOMrFK24fE02sqzv0QC7W1wBB1fQqaGpHDvhYb5jj457Bhgq3 server | KUNr5zVorhHLh" server | DNS name: "3glaj8RvVzPtUqTLYysfPdeP4cv89JaEav4aDykpBnal3p8kM2V5SF server | R9Y8r3hqPVGi0wdgdNqGecDCTJStLCZQzSSgEVG5r55IXg856MIDxpS1vY04y server | IopBksZ6cjlQlh9RGJQR5l4C1bhu4yf3fYM9nEtLCGSP5VpHFD4A6j20vLAkG server | ruDTIScMXmEfUPmczrEcFxFSoUMTfDszJ0SPtL4TOdh35YgIhHEGHwqXWJR5o server | 1ziIrEbaols6M" server | DNS name: "jfBRLQ3m9l3JiMyP1oN9Jii66vCLcgo9SCeyJNJOdeqGH60mvcCtcP server | AtNt4ecfpaZ2xEHUeAYyCyM5oSjJygGBMWbM36qB3vAZEkude4EbveS99Ezkl server | xb1DYWiD8IBPOhHvbbjsAgzz1GNb5m04LcAWKNAtULXbHYnDvGIiegy9i1yJy server | ihl0YcHXkTMkmQNM4mbOQV0drNnHXXe1qPbnbKp2IGoRXG8ABGaiA7s3SFziN server | yr29STHH4K4jc" server | DNS name: "BhJdlzWIejxoO0FiyZt7HOLwcxRZnsv2zE4blyT9N5ompVOlYKynN5 server | IPGNzY4UwWfjobxZtHzHOPCbPesxSo81ZIYQWEyID0D0LAL3g4UTIQiuKxh8d server | rhlTJnAp9jrUrzPylTuPUXzqz5MsjW59EwBgy01NQ53yPfhCFiHYrjv5sO8SK server | 4OamCrAPcBDp5ReWz0kFm1p6SsvzGql3PctTFg1k3ycHvz59ZjdD8SDIIanyr server | 80Sk8ebDPKl4C" server | DNS name: "Vc1t3jRuHTfyuWjH9JcfMtV1Msh1AEsydOrEZUtQ02enEGNNqezGf9 server | iOPvOF6Q9w0teXkXVFR2029aONQ4FmHB2jJhHTgVt5tJsxMSbMErCJREdhe1q server | 0UtPIansi8w5TtBfvz8EShf1e5tAdzKv76YjIdRoDYmMvwaw0Erb0p62V25al server | BrpNUeubS5H46AvEZDW4SDwlkfwHRPMqVBeeMYB6gBMkkKaEpKjzQG9BlMa6f server | dqkIycGA8GLHN" server | DNS name: "50Vmcybpwkj0uJ73ffd8MoBU42tAaiMLO58qmsqvbiXAo0QSkEXH3B server | pe7i91uGc5Vbx6tkVXMPRlDcFeDelj2xsVg7Zqg8B1tGMBG1KYanxjJr7K9Yd server | MbXVUZpygsTn3H9hDt8nQM0TeQpj18LInp3xkOefuyvJtOV7Hb3hIi6xiA3QT server | vfT4X1E3J93Vf4z6Owu4qSNE3rAoy05iyT3CPBZ0Bha4NVZSVsU2OX5kOY2QN server | 9hl0WK7GcxIQY" server | DNS name: "sa6ZQSsGftXv0mco5gHrzXhnczxoPXOJ5Qvxr8RvshqXZTMuhBl7bD server | 7WgoFWTTkuPFjbgdIe91la40yhiXjF1I1SyOiXyr70JjcTiMs2sddap1vDDcQ server | vVFvNGXFWqhWS5lG2pCwlSPxZ6rrYp8K5XUp9CLDtjCgurJYA3bUULhdVJEBR server | PorSQVEUSpuL3gODWCSOy1bXJDptyERNn3SREHHiMUFetAODqKd5yPrrCy4vm server | vIow8dAhxiy48" server | DNS name: "v5g211u6rdz66AyESwSbuC5rhwfHMMlmW2Qbc6kZtV92RxJEwxKdi1 server | P1kgGginvD1UYTbfnc3qM6D27nRHT9aFdSQ1ULGIars7OPFdi1UZvUENNiGxK server | j0AhI1QAshEy4KIIAFWtCekpEVxWsjR1NWvvQ33KMJAQVTOfCD12lXNegv9n5 server | dHbdV7xeIKnjOBUlU7mAlsmRd4r2REBXAIqd4DU696PXUjJeioK0VwtcXBHCn server | TSw0jfqUwjPy2" server | DNS name: "MaRFTzk64SgUyOfWZaEqxiC80yVvQFJvTCgARCKXCZ6DT8rhAHsPY2 server | QxqSt4jY957vmPBiOhJoRXbvs0e4eZF4ZJ2leuzZIJ37NMM8IM4ImWYTrbZ9J server | cKWk2jZztpeo1GsrUjT1X6OT2WY2JKTRRGnNLkPAhxu6A9UrYFvHyW62LSJ1M server | DuzIrHoVMOhb2tRAtNeEunPSeL2Hg7Dga1GQ8rXArmJ8TdVuyvXY2tBrZfaOD server | zRtfhUEHWQmwm" server | DNS name: "JEmYoUqLxeHAeVorotuQcx5ZtDShu1TuoUj3cWWSmCsK8zMGaX6nhe server | kjz3in7MaX7ymBBsos8QbwPXzh0hYgJeePFEySpmBFVDw2O3HiM0rp9JW1czz server | PQ0e4tydxgprv2riF3e6AQeTgvEBMerxj1iyStKd6OkdI2bQxLR5Yo8x2T2WG server | i9kvZcJD52NoS8cH9rzOI4wlEWL27nqazMntoUlRzM8jl0SGiDgZpLh7fP2Xd server | i1OJj6URlSLiU" server | DNS name: "sEPCDACBJxDR2BWPXXEx0vBRGOPQgWQXj0YNDdRKOFWYNSnxjOrRoH server | 7MHWpyt6nn6J3EMNUC8nujXWGAPbb0G1kXhjxM3k9OiQ3iFEeH5lDgxBMmEfx server | vYHon7icdzKJsa8zXCePb3O4E9OlbwuiQEyzxDCs0bazyklYRX0wa83X1zxf3 server | rK6g0Ac87eBY1ZxzaL95wNSfNJWcL8MYlewIM28J5j6uJxI3BbEbaTNMRRSuH server | lY7XzYCBk9cBe" server | DNS name: "T7SIlWc7dMVTFcABKatPq2xD5FXf3RTi45eadOAftNMdDKkdIg3GHH server | 3zWMzD1eiOdI9WwIQ3OmRGmYLg1CRtlX9rSIAAN6q1CMT9sDBcQQoz29Ht0QL server | ySIK1w0kfSaGZdt8vRmUQQbZIZoQFgZyKf8I7SYw1SFo3vyXb7fRPpp4NijoT server | sujJPOVUyOUKrYVuTaSAilTyH6bpLpDonf4Cx7fv52fUvDxE4qDWHZFyVZrir server | ZkEcu1xLwNd5c" server | DNS name: "ErkiEMlJGMGUcFCm11HA6Xx9sSyoimF8v2Kq6QRVitQUqSBAgGhWUl server | PbrC337y0kTb2sON3BxZZQktSwb083Yc8aRwONNDZvCEem3hyA5x5AlKKLFTe server | jv7O9daaiLImgBdTWtkAOzkNWitYHpJCMSloa3PP4h42VySm3STjODOxHYo6l server | Ud5dRp0SQKt0moKgIuuKNHEYSjBD1CcSCnUGD4symM9xCtI1aF6ROpCJVPhEZ server | XadJcbZwcZOcX" server | DNS name: "9l67Q0JKO6iQZdovXdHj2CY0scjUdu5ahhPwRt7wFVSlOptyj1Farp server | FXlNC63DnEYb2inEgzAN7qca4Xkuzxgp2Nqy2oiwHd6BvgG0SvKga1zOMmZOQ server | 7VOE0KnXmiBFkdpxBb6V8klamY3NjB8r59gpuDSJi2Ct3kpsVAi0Kzz97mk4u server | 1zdpx77A8zk7O4cKYL9aNfAg03cWLUXqLcld43mGYcGHJ5t5uIwJXV956rrKr server | DbbfMez6N3JrQ" server | DNS name: "smZI2wZuxmpvIiH5XM9YS6kjDJ4idoQ2jLU8HbU2BFebRBPUM6MpLT server | COp2u6EpbHSdLVNHZe0A27k7Va9Y53ae3fETlSzdVVTd6PtIpP6TO7InH00kg server | 8uftN2HQQHamX2UruJoJefjvnQVZRLvCopxVirFYYt1EyMcx5xtqS6spHCHKE server | 8xBLDN2TI1gdw2uPTorighi6T8dciLgO7qHvYtYLH7djEQlrKrBSvvqyFQv2d server | pD7NTu7qIVaK8" server | DNS name: "8KBqqr0mMHmteUBx6vWfhDmo5Xb3ZnnXvUpvik3MUhr157qW9obbzB server | uoHfnA1JvlR6EFGO4yqw7lNvX6Lhs2MHyuNQf2PM7F31OxSfNYmNWa4mjTGgc server | r35T4e1sXmKjhwyMFppkjPo44beBEMLt9aQCDbRhjD4WOaWuVeCclVE9cwY4A server | K8J6CeiAeUHbKJccFizlzcNZfwso4CAmSuGAsHRrTWp379ie2BmVL0Of8VPtq server | meMk1zpeJopID" server | DNS name: "DkrK2yUVCnc1MaV7ybqqft6Gt0hjLARnMbdvsSEw9pMYZPNaYBWnyf server | frCsSWCmiCMFKpcDg8YrfypKoQIi7IxblZPGdlCw2Z8cqQ1MGaLwPhmcjcDqP server | QAIcRXTSOE9hyGzgt0r3Fe5qbtjqsJSe2m9mQTqqGC1209HrQbOnEKwPUrTL5 server | diVZXbjiPJ99bkO2uFgmdDWyBLTJEGj13eoOiWdTYB1gztC6y0UeuxUid2cgj server | djvI7KXHh5ejA" server | DNS name: "D7g54I8Z8iBVTnLjypxer0Zra2cnSUcr9AgtIIgsRnd3evHnSMaqwO server | aefFN0Nu28uYkNFONdsSBMwBwuxU7yPvnkIBYUltGYwIIsnPLawokCtwyd6VN server | LQfiWn8em9xxM0X33gQKXFJiwLRKLKNkCF6KWHbAKKL3jJJjTPDjMFXJIdj5Q server | sLJ3eREQHyaCjJsO8MnHxAOcArnMBMysx75SwSmbfHFMq3dV4fVRhjFGW5s49 server | wwbc3MfUODHiB" server | server | Name: Certificate Subject Key ID server | Data: server | 88:6a:d9:0f:55:c2:b7:82:ca:ba:95:70:65:1c:6c:4b: server | cb:1b:ee:d0 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | d1:dc:33:a4:cc:03:0e:f5:c4:f2:dc:ef:f7:d4:83:3e: server | d5:53:df:4a server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:44:02:20:42:04:65:41:87:aa:d7:4f:78:04:ca:f8: server | 29:44:90:2c:c2:95:34:c2:86:bf:51:c6:7c:80:ff:d4: server | d8:a3:74:67:02:20:01:06:d9:23:6d:31:0e:29:35:1c: server | cc:71:f8:8b:ad:e6:be:bd:d0:04:61:cd:b3:f7:2c:b4: server | 7f:d4:6f:77:c5:83 server | Fingerprint (SHA-256): server | 13:E2:E7:72:C0:35:98:80:0E:69:E3:1A:75:7D:02:75:BD:D1:59:F3:BC:12:DA:2A:7A:F9:99:3E:B7:B1:B8:D3 server | Fingerprint (SHA1): server | 0E:D3:54:00:BC:DA:4C:A2:10:C3:D1:03:C8:40:D4:06:1F:7F:4D:48 server | server | Mozilla-CA-Policy: false (attribute missing) server | Certificate Trust Flags: server | SSL Flags: server | User server | Email Flags: server | User server | Object Signing Flags: server | User server | server | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT") server | + '[' transfer = connectionmigration ']' server | + RUST_LOG=debug server | + RUST_BACKTRACE=1 server | + neqo-server --cc cubic --qns-test transfer --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443' server | ++ tee -i -a /logs/server.log server | 0.000 DEBUG Logging initialized server | 0.002 DEBUG Default socket send buffer size is 212992 server | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing server | 0.002 INFO Server waiting for connection on: [::]:443 server | 0.801 DEBUG [Server] Unsupported version: 57414954 server | 0.801 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:35129 IpTos(Cs0, NotEct) len 15 sim | server:443 is available after 1.063071152s server | 0.802 DEBUG [Server] Unsupported version: 57414954 server | 0.802 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:35129 IpTos(Cs0, NotEct) len 15 server | 0.802 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.802 DEBUG [Server] Unsupported version: 57414954 server | 0.802 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:35129 IpTos(Cs0, NotEct) len 15 server | 0.802 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 | TEST_PARAMS: client | REQUESTS: 'https://server4:443/elpbvdavis' client | paths: -p /elpbvdavis client | server: server4 client | port: 443 client | CLIENT_PARAMS: server | 0.985 DEBUG [Server] Handle initial server | 0.985 INFO AddressValidation: no token; accepting server | 0.985 INFO [Server] Accept connection CID [16]: 97ed0de71c649c9e85c63369feaf85c0 server | 0.985 DEBUG Overwrite initial version Version1 ==> Version1 server | 0.988 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 0.988 INFO [Server ...] Received valid Initial packet with scid CID [8]: c5243e482a3df6a6 dcid CID [16]: 97ed0de71c649c9e85c63369feaf85c0 server | 0.988 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=97ed0de71c649c9e85c63369feaf85c0 server | 0.988 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 0.988 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 0.988 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] State change from Init -> WaitInitial server | 0.988 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 1200 server | -> RX Crypto { offset: 0, len: 248 } server | -> RX Padding { len: 897 } server | 0.988 DEBUG Read Ok(248) bytes server | 0.988 DEBUG 0-RTT: no token, no 0-RTT server | 0.989 DEBUG Read secret available for Handshake: SymKey [32]: a5dad811bd2cded363c73daab7904e35a61a3f1dd9d267f6beae0c3fbd8cd7cb server | 0.989 DEBUG Write secret available for Handshake: SymKey [32]: 150a0a516381acf72015ec3a0e9fa8aa329ca11028feee32f9412e44eff968f3 server | 0.989 DEBUG Writing transport parameters, msg=8 server | 0.989 DEBUG Read secret available for ApplicationData: SymKey [32]: fafcfe3f62eeea31322aee589ea4e84e270ba08c08a9ec8802e5f876448e213c server | 0.989 DEBUG Write secret available for ApplicationData: SymKey [32]: 94367a1d71ab21a79d246b6ca86f8de25091c39a0186a2a1edee1c2c858de58b server | 0.989 DEBUG [Agent 0x557af04d01d0] state -> InProgress server | 0.989 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 0.989 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 0.989 DEBUG [Crypto] Handshake keys installed server | 0.989 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 0.989 DEBUG [Crypto] Application write key installed server | 0.989 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 478, tv_nsec: 96381853 } server | 0.989 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:48293] Make permanent server | 0.989 DEBUG [unv-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] set as primary path server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] State change from WaitInitial -> Handshaking server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.989 DEBUG Building Initial dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG CRYPTO for in offset=0, len=90 server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=0 type=Initial pri-unv-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 147 server | TX -> Ack { largest_acknowledged: 0, 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: 90 } server | 0.989 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG CRYPTO for hs offset=0, len=1037 server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=0 type=Handshake pri-unv-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 0, len: 1037 } server | 0.989 DEBUG packet_sent this=0x557af04cfb40, pn=0, ps=1085 server | 0.989 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 0.989 DEBUG packet_sent this=0x557af04cfb40, pn=0, ps=147 server | 0.989 DEBUG ECN probing: sent 1 probes server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.989 DEBUG Building Initial dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG CRYPTO for hs offset=1037, len=1183 server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=1 type=Handshake pri-unv-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 1037, len: 1183 } server | 0.989 DEBUG packet_sent this=0x557af04cfb40, pn=1, ps=1232 server | 0.989 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 0.989 DEBUG ECN probing: sent 2 probes server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 0.989 DEBUG Building Initial dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG CRYPTO for hs offset=2220, len=1087 server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=2 type=Handshake pri-unv-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 1136 server | TX -> Crypto { offset: 2220, len: 1087 } server | 0.989 DEBUG packet_sent this=0x557af04cfb40, pn=2, ps=1136 server | 0.989 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 0.989 DEBUG ECN probing: sent 3 probes server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.989 DEBUG Building Initial dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 0.989 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 29.995190755s server | 0.989 DEBUG Setting timeout of 29.995190755s server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.989 DEBUG Building Initial dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 0.989 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 0.989 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.989 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 29.99516123s server | 0.989 DEBUG Setting timeout of 29.99516123s sim | Dropping packet 2 (1200 bytes) from 193.167.0.100 sim | Dropping packet 3 (50 bytes) from 193.167.0.100 sim | Dropping packet 4 (50 bytes) from 193.167.0.100 sim | Dropping packet 5 (47 bytes) from 193.167.0.100 sim | Dropping packet 6 (47 bytes) from 193.167.0.100 sim | Dropping packet 7 (47 bytes) from 193.167.0.100 server | 3.025 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Drop packet number space in server | 3.025 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 3.025 INFO [pri-unv-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] discarding a packet without an RTT estimate; guessing RTT=2.040660108s server | 3.025 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=8 type=Handshake pri-unv-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 47 server | -> RX Ping server | -> RX Padding { len: 2 } server | 3.025 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 137041961 } server | 3.025 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 137041961 } server | 3.025 DEBUG [pri-unv-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 137041961 } server | 3.025 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.025 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.025 DEBUG CRYPTO for hs offset=0, len=1176 server | 3.025 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=3 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 1232 server | TX -> Ack { largest_acknowledged: 8, 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: 1176 } server | 3.025 DEBUG packet_sent this=0x557af04cfb40, pn=3, ps=1232 server | 3.025 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.025 DEBUG ECN probing: sent 4 probes server | 3.025 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.026 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.026 DEBUG CRYPTO for hs offset=1176, len=1183 server | 3.026 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=4 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 1176, len: 1183 } server | 3.026 DEBUG packet_sent this=0x557af04cfb40, pn=4, ps=1232 server | 3.026 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.026 DEBUG ECN probing: sent 5 probes server | 3.026 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.026 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.026 DEBUG CRYPTO for hs offset=2359, len=1183 server | 3.026 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=5 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 1232 server | TX -> Crypto { offset: 2359, len: 1183 } server | 3.026 DEBUG packet_sent this=0x557af04cfb40, pn=5, ps=1232 server | 3.026 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.026 DEBUG ECN probing: sent 6 probes server | 3.026 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.026 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.026 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.026 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.026 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 101.802816ms server | 3.026 DEBUG Setting timeout of 101.802816ms server | 3.026 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.026 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.026 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.026 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.026 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 101.770696ms server | 3.026 DEBUG Setting timeout of 101.770696ms server | 3.057 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=9 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 51 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 21, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | -> RX Ping server | 3.057 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[0..=3] server | 3.057 DEBUG [LossRecovery] ACK for Handshake - largest_acked=3 server | 3.057 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=2464, cwnd=12320, state=SlowStart, new_acked=4685 server | 3.057 DEBUG Acked crypto frame space=hs offset=0 length=1176 server | 3.057 DEBUG Acked crypto frame space=hs offset=2220 length=1087 server | 3.057 DEBUG Acked crypto frame space=hs offset=1037 length=1183 server | 3.057 DEBUG Acked crypto frame space=hs offset=0 length=1037 server | 3.057 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 169075224 } server | 3.057 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 169075224 } server | 3.057 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 169075224 } server | 3.057 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.057 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.057 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=6 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 52 server | TX -> Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 0, Ce: 0})) } server | 3.057 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.057 DEBUG ECN probing: sent 7 probes server | 3.057 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.057 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.057 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.057 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.057 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 61.258746ms server | 3.058 DEBUG Setting timeout of 61.258746ms server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.058 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.058 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.058 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 61.223731ms server | 3.058 DEBUG Setting timeout of 61.223731ms server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=10 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 51 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 6, first_ack_range: 4, ack_ranges: [], ecn_count: None } server | -> RX Ping server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[0..=4] server | 3.058 DEBUG [LossRecovery] ACK for Handshake - largest_acked=4 server | 3.058 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.058 DEBUG Acked crypto frame space=hs offset=1176 length=1183 server | 3.058 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 169831548 } server | 3.058 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 169831548 } server | 3.058 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 169831548 } server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.058 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=7 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 52 server | TX -> Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 3, Ect0: 0, Ce: 0})) } server | 3.058 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.058 DEBUG ECN probing: sent 8 probes server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.058 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.058 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.058 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 56.414856ms server | 3.058 DEBUG Setting timeout of 56.414856ms server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.058 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.058 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.058 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.058 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 56.387075ms server | 3.058 DEBUG Setting timeout of 56.387075ms server | 3.059 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=11 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 51 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 16, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | -> RX Ping server | 3.059 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[0..=5] server | 3.059 DEBUG [LossRecovery] ACK for Handshake - largest_acked=5 server | 3.059 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.059 DEBUG Acked crypto frame space=hs offset=2359 length=1183 server | 3.059 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 170828112 } server | 3.059 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 170828112 } server | 3.059 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 170828112 } server | 3.059 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.059 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.059 DEBUG CRYPTO for hs offset=3542, len=1175 server | 3.059 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=8 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 1232 server | TX -> Ack { largest_acknowledged: 11, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 4, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 3542, len: 1175 } server | 3.059 DEBUG packet_sent this=0x557af04cfb40, pn=8, ps=1232 server | 3.059 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.059 DEBUG ECN probing: sent 9 probes server | 3.059 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.059 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.059 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.059 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.059 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 68.772544ms server | 3.059 DEBUG Setting timeout of 68.772544ms server | 3.059 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.059 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.059 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.059 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.059 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 68.745614ms server | 3.059 DEBUG Setting timeout of 68.745614ms server | 3.091 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=12 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 51 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 36, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | -> RX Ping server | 3.091 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[6..=8] server | 3.091 DEBUG [LossRecovery] ACK for Handshake - largest_acked=8 server | 3.091 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.091 DEBUG Acked crypto frame space=hs offset=3542 length=1175 server | 3.091 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 202799694 } server | 3.091 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 202799694 } server | 3.091 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 202799694 } server | 3.091 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.091 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.091 DEBUG CRYPTO for hs offset=4717, len=1175 server | 3.091 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=9 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect0) len 1232 server | TX -> Ack { largest_acknowledged: 12, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 5, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 4717, len: 1175 } server | 3.091 DEBUG packet_sent this=0x557af04cfb40, pn=9, ps=1232 server | 3.091 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.091 DEBUG ECN probing: sent 10 probes server | 3.091 DEBUG ECN probing concluded with 10 probes sent server | 3.091 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.091 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.091 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.091 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.091 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 60.317079ms server | 3.091 DEBUG Setting timeout of 60.317079ms server | 3.091 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.091 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.091 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.091 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.091 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 60.2914ms server | 3.091 DEBUG Setting timeout of 60.2914ms server | 3.123 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=13 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 51 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 40, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Ping server | 3.123 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[9..=9] server | 3.123 DEBUG [LossRecovery] ACK for Handshake - largest_acked=9 server | 3.123 WARN ECN validation failed, no ECN counts in ACK frame server | 3.123 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.123 DEBUG Acked crypto frame space=hs offset=4717 length=1175 server | 3.123 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 234834260 } server | 3.123 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 234834260 } server | 3.123 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 234834260 } server | 3.123 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.123 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.123 DEBUG CRYPTO for hs offset=5892, len=1175 server | 3.123 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=10 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1232 server | TX -> Ack { largest_acknowledged: 13, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 6, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 5892, len: 1175 } server | 3.123 DEBUG packet_sent this=0x557af04cfb40, pn=10, ps=1232 server | 3.123 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.123 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.123 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.123 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.123 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.123 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 52.923261ms server | 3.123 DEBUG Setting timeout of 52.923261ms server | 3.123 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.123 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.123 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.123 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.123 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 52.887594ms server | 3.123 DEBUG Setting timeout of 52.887594ms server | 3.155 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=14 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 51 server | -> RX Ack { largest_acknowledged: 10, ack_delay: 43, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Ping server | 3.155 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[10..=10] server | 3.155 DEBUG [LossRecovery] ACK for Handshake - largest_acked=10 server | 3.155 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.155 DEBUG Acked crypto frame space=hs offset=5892 length=1175 server | 3.155 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 266866149 } server | 3.155 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 266866149 } server | 3.155 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 266866149 } server | 3.155 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.155 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.155 DEBUG CRYPTO for hs offset=7067, len=1175 server | 3.155 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=11 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1232 server | TX -> Ack { largest_acknowledged: 14, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 7, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 7067, len: 1175 } server | 3.155 DEBUG packet_sent this=0x557af04cfb40, pn=11, ps=1232 server | 3.155 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.155 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.155 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.155 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.155 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.155 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 46.554152ms server | 3.155 DEBUG Setting timeout of 46.554152ms server | 3.155 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.155 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.155 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.155 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.155 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 46.498268ms server | 3.155 DEBUG Setting timeout of 46.498268ms server | 3.187 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=15 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 51 server | -> RX Ack { largest_acknowledged: 11, ack_delay: 38, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Ping server | 3.187 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[11..=11] server | 3.187 DEBUG [LossRecovery] ACK for Handshake - largest_acked=11 server | 3.187 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.187 DEBUG Acked crypto frame space=hs offset=7067 length=1175 server | 3.187 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 298835742 } server | 3.187 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 298835742 } server | 3.187 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 298835742 } server | 3.187 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.187 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.187 DEBUG CRYPTO for hs offset=8242, len=1175 server | 3.187 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=12 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1232 server | TX -> Ack { largest_acknowledged: 15, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 8, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 8242, len: 1175 } server | 3.187 DEBUG packet_sent this=0x557af04cfb40, pn=12, ps=1232 server | 3.187 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.187 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.187 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.187 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.187 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.187 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 40.794842ms server | 3.187 DEBUG Setting timeout of 40.794842ms server | 3.187 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.187 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.187 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.187 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.187 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 40.759987ms server | 3.187 DEBUG Setting timeout of 40.759987ms server | 3.219 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=16 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 51 server | -> RX Ack { largest_acknowledged: 12, ack_delay: 48, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Ping server | 3.219 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[12..=12] server | 3.219 DEBUG [LossRecovery] ACK for Handshake - largest_acked=12 server | 3.219 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.219 DEBUG Acked crypto frame space=hs offset=8242 length=1175 server | 3.219 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 480, tv_nsec: 331038735 } server | 3.219 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 331038735 } server | 3.219 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 331038735 } server | 3.219 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.219 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.219 DEBUG CRYPTO for hs offset=9417, len=558 server | 3.219 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=13 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 615 server | TX -> Ack { largest_acknowledged: 16, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 9, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 9417, len: 558 } server | 3.219 DEBUG packet_sent this=0x557af04cfb40, pn=13, ps=615 server | 3.219 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.219 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.219 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.219 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.219 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.219 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 21.825472ms server | 3.219 DEBUG Setting timeout of 21.825472ms server | 3.219 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.219 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.219 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.219 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.219 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 21.788743ms server | 3.219 DEBUG Setting timeout of 21.788743ms server | 3.242 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.242 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.242 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.242 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.242 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 5.021368731s server | 3.242 DEBUG Setting timeout of 5.021368731s server | 3.242 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.242 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.242 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.242 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.242 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 5.021339015s server | 3.242 DEBUG Setting timeout of 5.021339015s server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=17 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 90 server | -> RX Ack { largest_acknowledged: 13, ack_delay: 164, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Crypto { offset: 0, len: 36 } server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=hs, ranges=[13..=13] server | 3.252 DEBUG [LossRecovery] ACK for Handshake - largest_acked=13 server | 3.252 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=615 server | 3.252 DEBUG Acked crypto frame space=hs offset=9417 length=558 server | 3.252 DEBUG Read Ok(36) bytes server | 3.252 DEBUG [Agent 0x557af04d01d0] 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 | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] TLS connection complete server | 3.252 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 363727323 } server | 3.252 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 3.252 DEBUG [Crypto] application read keys installed server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] State change from Handshaking -> Connected server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] State change from Connected -> Confirmed server | 3.252 DEBUG PMTUD started with probe size 1380 server | 3.252 INFO [Server 97ed0de71c649c9e85c63369feaf85c0] Connection established server | 3.252 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 363727323 } server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=18 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 76 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [194, 6, 254, 83, 242, 17, 254, 97], stateless_reset_token: [118, 147, 237, 149, 157, 0, 94, 84, 136, 152, 230, 75, 250, 131, 221, 195] } server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 3.252 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 363727323 } server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.252 DEBUG Building Handshake dcid Some(CID [8]: c5243e482a3df6a6) scid Some(CID [10]: f1025d448153cf29714d) server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=14 type=Handshake pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 52 server | TX -> Ack { largest_acknowledged: 17, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 10, Ect0: 0, Ce: 0})) } server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Drop packet number space hs server | 3.252 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 3.252 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=0 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1332 server | TX -> Ack { largest_acknowledged: 18, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [245, 183, 229, 18, 157, 127, 101, 141, 155, 89], stateless_reset_token: [54, 84, 14, 99, 75, 80, 232, 153, 137, 239, 199, 3, 108, 199, 120, 233] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [41, 187, 20, 207, 13, 24, 98, 140, 211, 120], stateless_reset_token: [245, 191, 95, 58, 199, 175, 125, 181, 186, 213, 50, 201, 129, 161, 22, 112] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [236, 14, 13, 157, 209, 110, 136, 153, 244, 250], stateless_reset_token: [127, 110, 82, 49, 227, 95, 99, 243, 136, 112, 161, 18, 26, 112, 79, 143] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [252, 126, 157, 0, 183, 197, 204, 117, 218, 204], stateless_reset_token: [212, 180, 133, 141, 176, 203, 159, 18, 193, 75, 218, 77, 224, 247, 89, 212] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [128, 200, 91, 4, 253, 110, 206, 135, 207, 104], stateless_reset_token: [39, 202, 83, 154, 170, 232, 54, 29, 17, 54, 181, 244, 220, 97, 147, 45] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [36, 95, 91, 2, 198, 239, 114, 9, 192, 132], stateless_reset_token: [240, 218, 148, 73, 147, 193, 82, 181, 146, 252, 75, 133, 118, 239, 8, 204] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [176, 247, 207, 175, 248, 158, 244, 80, 206, 9], stateless_reset_token: [199, 168, 69, 28, 157, 79, 168, 92, 204, 197, 1, 130, 31, 137, 47, 15] } server | TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 50000, ignore_order: false } server | TX -> Padding { len: 1026 } server | 3.252 DEBUG packet_sent this=0x557af04cfb40, pn=0, ps=1280 server | 3.252 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.253 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.253 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.253 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 20.490545ms server | 3.253 DEBUG Setting timeout of 20.490545ms server | 3.253 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] send session ticket 406b001097ed0de71c649c9e85c63369feaf85c00104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0af1025d448153cf29714d110c000000018a0a1aca000000016ab200c0000000ff02de1a0243e8200100686921 server | 3.253 DEBUG Path = 'elpbvdavis' server | 3.254 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.254 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.254 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.254 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 19.457232ms server | 3.254 DEBUG Setting timeout of 19.457232ms server | 3.274 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.274 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.274 DEBUG Sending PMTUD probe of size 1380, count 1 server | 3.274 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=1 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1332 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 0, len: >>1303, fin: false } server | 3.274 DEBUG packet_sent this=0x557af04cfb40, pn=1, ps=1332 server | 3.274 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.274 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.274 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.274 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 31.681438ms server | 3.274 DEBUG Setting timeout of 31.681438ms server | 3.274 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.274 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.274 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.274 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 31.655349ms server | 3.274 DEBUG Setting timeout of 31.655349ms server | 3.294 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=19 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 35 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 1178, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.294 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=ap, ranges=[0..=0] server | 3.294 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 server | 3.294 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=1332, cwnd=12320, state=SlowStart, new_acked=1280 server | 3.294 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.294 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.294 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.294 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 8.005126ms server | 3.294 DEBUG Setting timeout of 8.005126ms server | 3.294 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.294 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.294 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.294 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 7.892445ms server | 3.294 DEBUG Setting timeout of 7.892445ms server | 3.303 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.303 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.303 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=2 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1232 server | TX -> Stream { stream_id: 0, offset: 1303, len: >>1202, fin: false } server | 3.303 DEBUG packet_sent this=0x557af04cfb40, pn=2, ps=1232 server | 3.303 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.303 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.303 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.303 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 26.752473ms server | 3.303 DEBUG Setting timeout of 26.752473ms server | 3.303 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.303 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.303 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.303 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 26.727516ms server | 3.303 DEBUG Setting timeout of 26.727516ms server | 3.315 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=20 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 35 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 1157, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 3.315 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=ap, ranges=[0..=1] server | 3.315 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 3.315 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1332 server | 3.315 DEBUG PMTUD probe of size 1380 succeeded server | 3.315 DEBUG PMTUD started with probe size 1420 server | 3.315 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 3.315 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.315 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.315 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.315 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 14.014496ms server | 3.315 DEBUG Setting timeout of 14.014496ms server | 3.315 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.315 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.315 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.315 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 13.937492ms server | 3.315 DEBUG Setting timeout of 13.937492ms server | 3.322 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=21 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 38 server | -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 20000, ignore_order: true } server | 3.322 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 480, tv_nsec: 453721214 } server | 3.322 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.322 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.322 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.322 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 6.748396ms server | 3.322 DEBUG Setting timeout of 6.748396ms server | 3.322 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.322 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.322 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.322 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 6.68087ms server | 3.322 DEBUG Setting timeout of 6.68087ms server | 3.330 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 3.330 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.330 DEBUG Sending PMTUD probe of size 1420, count 1 server | 3.330 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=3 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1372 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 2505, len: >>1341, fin: false } server | 3.330 DEBUG packet_sent this=0x557af04cfb40, pn=3, ps=1372 server | 3.330 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.330 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.330 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.330 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 11.684138ms server | 3.330 DEBUG Setting timeout of 11.684138ms server | 3.330 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.330 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.330 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.330 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 11.663659ms server | 3.330 DEBUG Setting timeout of 11.663659ms server | 3.343 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.343 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.343 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=4 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 35 server | TX -> Ack { largest_acknowledged: 21, ack_delay: 2678, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 4, Ect0: 0, Ce: 0})) } server | 3.343 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.343 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.343 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.343 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 13.568699ms server | 3.343 DEBUG Setting timeout of 13.568699ms server | 3.343 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.343 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.343 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.343 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 13.540717ms server | 3.343 DEBUG Setting timeout of 13.540717ms server | 3.344 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=22 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 35 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 1151, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | 3.344 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=ap, ranges=[0..=2] server | 3.344 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 3.344 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=1372, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.344 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.344 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.344 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.344 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 11.66592ms server | 3.344 DEBUG Setting timeout of 11.66592ms server | 3.344 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.344 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.344 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.344 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 11.577906ms server | 3.344 DEBUG Setting timeout of 11.577906ms server | 3.357 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 3.357 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.357 DEBUG CRYPTO for ap offset=0, len=23 server | 3.357 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=5 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1332 server | TX -> Stream { stream_id: 0, offset: 3846, len: 1274, fin: true } server | TX -> Crypto { offset: 0, len: 23 } server | 3.357 DEBUG packet_sent this=0x557af04cfb40, pn=5, ps=1332 server | 3.357 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.357 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.357 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.357 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 22.138419ms server | 3.357 DEBUG Setting timeout of 22.138419ms server | 3.357 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.357 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.357 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.357 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 22.117009ms server | 3.357 DEBUG Setting timeout of 22.117009ms server | 3.371 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=23 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 35 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 1149, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 3.371 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=ap, ranges=[0..=3] server | 3.371 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3 server | 3.371 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=1332, cwnd=12320, state=SlowStart, new_acked=1372 server | 3.371 DEBUG PMTUD probe of size 1420 succeeded server | 3.371 DEBUG PMTUD started with probe size 1470 server | 3.371 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 3.371 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.371 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.371 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.371 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 6.286968ms server | 3.371 DEBUG Setting timeout of 6.286968ms server | 3.371 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.371 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.371 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.371 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 6.205066ms server | 3.371 DEBUG Setting timeout of 6.205066ms client | /logs/C5243E482A3DF6A6.keys client | /logs/keys.log exists server | 3.378 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 3.378 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.378 DEBUG Sending PMTUD probe of size 1470, count 1 server | 3.378 DEBUG CRYPTO for ap offset=23, len=298 server | 3.378 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=6 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 1422 server | TX -> Ping server | TX -> Crypto { offset: 23, len: 298 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 52, 87, 201, 197, 179, 57, 178, 150, 83, 133, 16, 166, 140, 247, 121, 3, 179, 23, 32, 12, 122, 44, 15, 69, 93, 101, 120, 125, 80, 208, 73, 163, 202, 123, 88, 92] } server | TX -> Padding { len: 1048 } server | 3.378 DEBUG packet_sent this=0x557af04cfb40, pn=6, ps=1422 server | 3.378 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.378 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.378 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.378 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 21.446335ms server | 3.378 DEBUG Setting timeout of 21.446335ms server | 3.378 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.378 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.378 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.378 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 21.427199ms server | 3.378 DEBUG Setting timeout of 21.427199ms server | 3.389 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=24 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 34 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 7, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 3.389 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] Rx ACK space=ap, ranges=[2..=5] server | 3.389 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5 server | 3.389 DEBUG on_packets_acked this=0x557af04cfb40, limited=1, bytes_in_flight=1422, cwnd=12320, state=SlowStart, new_acked=1332 server | 3.389 DEBUG Acked crypto frame space=ap offset=0 length=23 server | 3.389 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.389 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.389 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.389 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] delay duration 8.548222ms server | 3.389 DEBUG Setting timeout of 8.548222ms server | 3.389 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=25 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, Ect1) len 32 server | -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" } server | 3.389 INFO [Server 97ed0de71c649c9e85c63369feaf85c0] ConnectionClose received. Error code: Transport(0) frame type 0 reason server | 3.389 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 488, tv_nsec: 448032735 } } server | 3.389 DEBUG [pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293] Path validated Instant { tv_sec: 480, tv_nsec: 500582063 } server | 3.389 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.389 DEBUG Building Short dcid Some(CID [8]: c5243e482a3df6a6) server | 3.389 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 480, tv_nsec: 500582063 } server | 3.389 DEBUG [Server 97ed0de71c649c9e85c63369feaf85c0] pn=7 type=Short pri-path:c5243e482a3df6a6 [::]:443->[::ffff:193.167.0.100]:48293 IpTos(Cs0, NotEct) len 38 server | TX -> Ack { largest_acknowledged: 25, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 8, Ect0: 0, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" } server | 3.389 DEBUG Setting timeout of 7.947345105s server | 3.389 DEBUG Setting timeout of 7.947336639s client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 12:27:02,623 Using the client's key log file. 2025-04-30 12:27:02,632 2025-04-30 12:27:02,633 Using the client's key log file. 2025-04-30 12:27:02,633 Using the client's key log file. 2025-04-30 12:27:02,640 2025-04-30 12:27:02,641 Using the client's key log file. 2025-04-30 12:27:02,641 Using the client's key log file. 2025-04-30 12:27:03,294 Check of downloaded files succeeded. 2025-04-30 12:27:03,622 Server sent 9975 bytes in Handshake CRYPTO frames. 2025-04-30 12:27:03,985 Received a 1200 byte Initial packet from the client. Amplification limit: 3600 2025-04-30 12:27:03,986 Received a 1232 byte Handshake packet from the server. Total: 1232 2025-04-30 12:27:03,986 Received a 1232 byte Handshake packet from the server. Total: 2464 2025-04-30 12:27:03,986 Received a 1136 byte Handshake packet from the server. Total: 3600