2025-04-30 09:15:20,216 Generated random file: lqebgvlzlr of size: 5120
2025-04-30 09:15:20,216 Requests: https://server4:443/lqebgvlzlr
2025-04-30 09:15:20,606 
2025-04-30 09:15:20,607 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_orxq4shd/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_ee9gylyk/ DOWNLOADS=/tmp/download_uup0qdn8/ SERVER_LOGS=/tmp/logs_server_253798de CLIENT_LOGS=/tmp/logs_client_tb6qn4ol SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=stammw/quinn-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/lqebgvlzlr"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 09:15:37,072  Container sim  Recreate
 Container sim  Recreated
 Container client  Recreate
 Container server  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | Setting up routes...
client  | Executing client
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
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.I1wMc6Kx9g
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.I1wMc6Kx9g
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.I1wMc6Kx9g -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  |             6c:d3:88:15:c3:a2:f0:27:cf:a1:34:4c:9a:c9:b6:87:
server  |             f0:90:b8:4d
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner intermediate 8"
server  |         Validity:
server  |             Not Before: Wed Apr 30 09:15:20 2025
server  |             Not After : Sat May 10 09:15:20 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:f0:08:2c:e1:b4:7b:fa:b0:1a:4f:1f:d7:cc:41:d0:
server  |                     d0:dd:15:6f:4e:94:7c:18:d6:e0:1c:a5:74:6a:96:20:
server  |                     6b:18:9b:0b:2b:a7:d4:fc:ac:b6:4f:72:99:5f:bd:f2:
server  |                     65:7e:7c:19:69:51:79:11:3e:35:03:7d:70:ee:10:c0:
server  |                     92
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: "4TdxJZ2eOblaKqSNa2kKshSduWLkHVK7SyKZAnxI7TXnoYsRuMz6R8
server  |                 3H4yN6OLQyx4XvG0LTxTdrIZdbeJ5PjWn5YcPnbyMfm58eVqk7OdV2kjdFsuB
server  |                 8e9swFCinzpdQN17Ac5HuPFv5liMzlQs5osh4CnZDhwqbAIJTVdu8bmjdUZcP
server  |                 W6BL72I0gtuJDfgL84wRkfT8oJ0CnkFkQjlRtuAVG9OoXAw0R7aEo1vcjQPC2
server  |                 u0DUNQxvfFDdG"
server  |             DNS name: "a7R5AZlEl4vMvo3wlApHViGdihlSgzfMQaxWZCsf8Ixiy2kKFJEAuf
server  |                 n4Gt0hyrMOieL45ATUQhDkFiDD2cnCzo6z1GJTOG4YVm7lRaMaJspbbfr0WgW
server  |                 GoQ2jHCe2MgJcruA4EDtOdWw0h542rZzqcS0HZJM4rEncIeTnsXcFlhvrkm3z
server  |                 KEKNo9KVBVIhPpCkgzXMQRFu7JXt2dEhkm12MXqXmhys2RGRKigZKRUJQK8qq
server  |                 lwf8foG2wGqFS"
server  |             DNS name: "DJxorJpH2Eb16iaqXhIbiugSlWdUuXL28ZQV6OQ00A6vs5vgbBj7M0
server  |                 xwq8EMkDHbAyR7gPuSqvOdxCJCeW1byCMPR9Ru4QmyacbWuaY33nqkGorR5Q1
server  |                 WXdNjOTEyuxARdTwcoMIz0mNIlXZYqCAnusAORWbrOCAisDxIN8b1GZDZp0Fw
server  |                 vC1QNuroRQeGJtNInzs0SM8V95bPVfcU2Qy0qWUY6MdjKiwMpFmTfNs4wLUnh
server  |                 l3nY4x2Ded8VG"
server  |             DNS name: "ZM1idrOZD2WLnjaonGijqs6KDEzzzViedjArBKLnmazOBacsHOh3B1
server  |                 8JvTL4OHiawnEbbqnSKf0coxQdDpTdImdvp07EkvHSoK0tNss7RTrVZU9pl8O
server  |                 OeIdmIeWdheXHUZ0T8Vw3YerDD9cfO68rrxjRX9lrrZq3LWtT4cRW0CTKeaTi
server  |                 H3FwUviIK9ihpbnUif1DeRTNJa69MwqBG8uLKWDwNEvNrERfeQJOEyIWhAuAv
server  |                 oVkeXs6fKfyAB"
server  |             DNS name: "MpqV2m2sKy9WA8051JewDN1Mj22eC6L2o4lxJNs53sxJeK3P2C51oF
server  |                 Xv8SUPleUPoad9FWIp2vLdFfrVl6E9CIlUXeqaaz8Zzukw9jHtbr4MTVcVmfZ
server  |                 DLB7FqFdEPz633szhpSXUrorpnQakz4SjKvBXCFfyyfzeIcptwZRVMUUh48sl
server  |                 vnnnP859aQ2IttVh7ipAzpCoJhmuhor2IPRiE1uOgSio0nIreVbh7Njpm3xVA
server  |                 Cdz5G3OIn9pRs"
server  |             DNS name: "ozhu7b5Pk4Y9UgD5Vr3JeXiqDuNQnBm6xFXW5MnWLa6S98gf64GUOM
server  |                 05m7SkceIwnmQfWe9TS7d7GaK4T2NTdraEgWHnvEcD7mgL9rasznkwL3CqIQE
server  |                 9C3upJdOPMESX7yATrvNb99gvQUTLEnSPbv7G4gPmGfXSt3yQm7nkICbKQCFR
server  |                 CpCH7l2zg2HUl3Ry6gPLpSoCcpfGCxbkzfrykYtTVUTJWNJrqXSJbCeb3hWZj
server  |                 GAxSvk4PiAn55"
server  |             DNS name: "QKhSx5xHGEVs6a4B8ym0lnmcyQbPdLziH5DoDTL8pVlARSCO9gE6dC
server  |                 7yBHQuR1JEPbUpcNUZcbx0szP6WR2Y0Z2SE9uU59JUNyrvf8z7nTXOarJYkEA
server  |                 7doG9Rtew9DZAPHkpKSAEyXEdCkP9VGEkTLiOGSivSdKoEKW2Pw34U2NZcBT0
server  |                 nkNnwwSQxjjdFZLJK717En05r5Js1i8OHUayQ4PufY4gAOb7Kc09DOaC450XP
server  |                 vlPrBNEozQDCm"
server  |             DNS name: "N6M9AARlKUBK1mP4pqO4BjVF9Gi5kwBTTZUr3I68HfckmbhT1IdEe3
server  |                 IAzYqD1DoYYod5Ctvlle3RospVx6JVuBNMA7lSj89MZzKoU1GYC3HySh9vqAy
server  |                 vo8vHpiexdqgoCYCkCv8sCEnqEy5zlKIPzuyA7CqQ0pPQUf1tA81ljRyuBATw
server  |                 U30iLpoWlOpfQPVT4aZcHCFWaY9e7afRhPVF396FrQjzMgYCqSRTvhSzRn0n7
server  |                 NYYlMwV5nRvrU"
server  |             DNS name: "9idjvQ9spPlSVDDKwqduEyhw5Hr9jcXjRBKzs22oC8lwtqyJ9SBxCF
server  |                 aDej73kPadGN1hQjwxYx7PymCvByl5BsTv6JqifiuZ7ytIbF3Ot9E0emCcxiR
server  |                 lCkXmkYPU17Tui9Pfvj6IgAwqUgHg4uRlLGmlxtLIlaPZUWSJIqt8YNncAnOC
server  |                 k4VbkCUwWEg4BC0ExqJULHE8ymnnmEjTFoBcEGlfSeLEFksCNfLEaJ4ucF77D
server  |                 rpBQuQey3QFOH"
server  |             DNS name: "IdNQriLDBxuArwTX1cU8DChYeEe8GSjzlsrHhu7kKN7B09jiKn71Mx
server  |                 6J2VJi1LICv6jWjiPiBNxRSCCsc5jHQna5DhgWEOQBL74hxzHkDH2w7tnzuI4
server  |                 kYCx30YbGdgzN6qtPA6QOT3OWn3taGMWXt7I0BFE20cSFYu8rCHYlIEERBn00
server  |                 J0kcFoUcv2qE2088jFbpM8HCx7VRFKCaLDfO5WDv871eBJ5CTW179k9UUrrEM
server  |                 zdfCsliVgwpX1"
server  |             DNS name: "Yu6i5FB4BcS7fFyrzJAKnG10QHFkEDmRAeyJFqBFailNJkmNmFQHhl
server  |                 4uFvNcLmRkZQXlabaSzFRBVdTF3bGdiB2Pnmu2PWln9NrPGkU6k5vHCSNcXLi
server  |                 OVjbRUWjxkodQQ80EKHErX7EM8JNgqMdGIoJvfL8WgR0KBdgYEhM3OLhbCxiw
server  |                 FNSgNeHkbOzLad6WaRHarzlN1GI2T1xeWR5MTFdrGge3G3hq8fMZllorcAmo3
server  |                 fxLQgUeKdLS2k"
server  |             DNS name: "mu4EVaeqt104PoMZfHjxP5HkCQfpFi6KdyDNtJNF3yWaQK69zAjPLB
server  |                 SpYcec3XjhAABvrV7k8IG1qXhQaE9yME5vSv8sIY6f3zaBjryidhEpTKLp4Pk
server  |                 dTd86pJvUvPH5ocPHPlpLj6Jl3vIpV0GpzpNpMs5HTmLQds0eVz6snMp1n6RZ
server  |                 zHzc3aCw5VToS1e8x9Y8so1kzeWErZmuyuzQficm1HjNeplEgrZCaH4wLStAA
server  |                 76DiDPjzdML3D"
server  |             DNS name: "x8QrU7sC4AmXw2ugg6go4lH0UOcZgHv3DJIt7zStdQJA4aZFWS456e
server  |                 EYGGhE6OUdGjsqLQeDfTtDQhRGaysNzQBlcgvWCgzma9xOKTfri2oqWdTT9eV
server  |                 E3e4XMvzEzoNzqys9EYSUGR8iTr7m7YLOGBxCrVsip5driUwYahboPgfPxmwx
server  |                 JnmLtaKyc4m704DIZcQYP9t5jt0SwdCjRy0xJFNCnok6LHjeXYF4af1l5RAF3
server  |                 kms6S8lx5yngr"
server  |             DNS name: "69pfunhoRdiz4HpL9pxugTrT4keUgDdecRtQ9SmOU745BtBZVKdirI
server  |                 9G3p0MhCVMIXTbtWNhFZKa0DvBmqfiAP2PhQ6akoQ4ku6VlnVLvq1VFprQq1R
server  |                 crYzUNXIcyzj6QPvVB4Xtq3AqFpbz7VUtZg1jyLDMNyyhSAPf4uATUYdGxhGP
server  |                 L6q5rAas9AB1456FCwuLcy0uUk6AxZ9oM4uNszmDYzMVXzNIvLW6e6jzABp2a
server  |                 WBUYoKqZo0QJg"
server  |             DNS name: "vmqdKtXrJ7RncS4bhh1xJtZOmpYDNBWJ5iDkAHClv7JfVejAd69RX6
server  |                 nzrC64jvRmRTYhRaJOKLTjGVB7vXO1uP4rEo5nsG4ibOn1i0u3b8Qq5qPTpp9
server  |                 bw8ANJmFnhewF5BXAXzl66BDbx9FekDJJFFxpnV8UoMkDbJ6KJsvvV3G1cc4D
server  |                 B9BWBW2GUbuqnKNF0Re1kXXFyETlVhWXfh4l7vBQkIOhyjzkoj5vt9j6mrdlL
server  |                 NSGJcftkAYrqA"
server  |             DNS name: "jPvPUjg4AU6SfTRi1s6upXrUDMn5WVHu4K51pFDvh51OTstjWH2fk0
server  |                 vFTE58fk7n7nDkaJDg4H2erugboQp0KWdyk23dXjnwnhtmRLVsVnlrdOxEefY
server  |                 acJppE0K5t8YzvNyc6pNANBcvWpSCE05SynPwJJJARBFuUBDvkPsmTJR59yhO
server  |                 AmpKuZ7p6FAEftbiLCuTv1oKJy3uM9Bi6GN1Kih0D67tLkOxwLBeaEzVmnkAR
server  |                 wy8KsvpWONnn2"
server  |             DNS name: "INIRMIsDYaTeAfqgHk6oenq24sEZOfM9Vt0KxDfwdBp9oQIjnbcbMG
server  |                 eHToRPMnb5aCed8s80p64kPVZCmyASPfwrFE5teeOkUzljg8RPy7BJy1ZU2Kl
server  |                 o5vCTajIIPw7AivjkLbP0Utuk0u8HTReiM6b7kUK9QZhZL6WbrOfhQTS4mZzK
server  |                 pX0IhOyWJDWRb81Ng11nHsHjgm4cqVH51LDWMygP60A4TmtEIArmNeHnsmzRr
server  |                 gyb73b6u5CfwG"
server  |             DNS name: "mAN6dao6keY5oFgOgvmTYCKMlPS009xYeWNsVongkJBPB7OSn5Fkwi
server  |                 Hj5Jzlhqbr2I3YmlQfDUkwGxybfSRhLUvE73qeS2kfoQfFLvcZiW0qvpVpWTQ
server  |                 pB4Wlc0T21yZwGH7oEybZSInLHVNYPMpdctKBYIKcwoBpnfgLLAip10JjRXlq
server  |                 lU0Ai5qtMqdY6X4rITQsm8ZaQWtki8tqDPw3irZjbgM2hbyRM1WDqNEIwhREv
server  |                 3umwqPCMdRZ3x"
server  |             DNS name: "pC41iZg1ZhL3XB6hrYO41BrXJpQoAJsDncPHAOaf0o0ucorvvGjJm8
server  |                 sWIx9W8GIjDWfeEmL1B2Hb0qBygCaFUMeIaeUgrzFwMWtMYX8iE3Tdin27ldx
server  |                 yfpdXsa37gAAxypaiuhW7iAs4uMHo4Yt6fiqLZKobwL74ax1iijXYN7KtyEc2
server  |                 em6LlZGkWJ57ZaE1uzqgtxPaFiJrdxNHKE9fjEbyQiYJvMmw5XcdHIZ5nxhw6
server  |                 iM9wmFHzzhm9f"
server  |             DNS name: "bsGy5jYODSOUVITnDL64ZvbOWkwkFtic8j9OwkooTKIdnffZq8Ak0d
server  |                 r5eyQ2alZ2DosJ0ZBy2LNAOMCvufyDqYVhQiLjAr0f4beqhJdubOOubJrFLOZ
server  |                 vt2FESc6qy6JOkY4MOUPvBrFl61WelbOaZ5f4kLqs2td4cKnZk8MgeoMqxKVF
server  |                 zEBPgUwrbg1PUFfYWgPCQhbazeWT3ct2t8cKvdiFLTY9XZWdvCQ6x4asfW6EW
server  |                 y0zhv1jrktJro"
server  | 
server  |             Name: Certificate Subject Key ID
server  |             Data:
server  |                 4a:7d:ac:2d:79:db:ba:46:b7:da:98:fc:6a:d7:56:49:
server  |                 b7:1a:c9:81
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 4a:7b:a8:6e:ef:e5:d1:d7:5a:d5:92:df:36:59:4e:a9:
server  |                 51:40:2c:12
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:44:02:20:76:0e:9f:4d:a0:61:39:e0:31:4d:2e:7e:
server  |         72:fc:f2:d6:07:36:55:75:a4:9e:8f:da:01:f7:6b:91:
server  |         a2:12:a2:d2:02:20:4e:d3:7e:ff:13:f6:72:59:ba:73:
server  |         b3:07:e4:d0:0f:35:63:6a:5c:90:25:2b:18:4a:ec:fb:
server  |         fe:c3:44:04:d5:0d
server  |     Fingerprint (SHA-256):
server  |         27:1B:82:55:56:84:37:82:46:8E:5E:F3:C6:CA:76:8D:F1:3A:B2:A8:32:3C:F0:E7:2E:87:3B:76:5B:2B:6E:A7
server  |     Fingerprint (SHA1):
server  |         13:17:8C:EC:83:07:0A:FA:39:DC:0A:21:18:BC:49:7B:A6:AA:F4:07
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.754 DEBUG [Server] Unsupported version: 57414954
server  | 0.754 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:56457 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.02564735s
server  | 0.755 DEBUG [Server] Unsupported version: 57414954
server  | 0.755 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:56457 IpTos(Cs0, NotEct) len 15
server  | 0.755 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.755 DEBUG [Server] Unsupported version: 57414954
server  | 0.755 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:56457 IpTos(Cs0, NotEct) len 15
server  | 0.755 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
server  | 3.964 DEBUG [Server] Handle initial
server  | 3.964 INFO AddressValidation: no token; accepting
server  | 3.964 INFO [Server] Accept connection CID [20]: 13dddb8b2d04f9597c929061d29eb496fb275b7f
server  | 3.964 DEBUG Overwrite initial version Version1 ==> Version1
server  | 3.967 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 3.967 INFO [Server ...] Received valid Initial packet with scid CID [8]: a6c48224bc43d49f dcid CID [20]: 13dddb8b2d04f9597c929061d29eb496fb275b7f
server  | 3.967 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=13dddb8b2d04f9597c929061d29eb496fb275b7f
server  | 3.967 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 3.967 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 3.967 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] State change from Init -> WaitInitial
server  | 3.967 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1200
server  |   -> RX Crypto { offset: 0, len: 286 }
server  |   -> RX Padding { len: 855 }
server  | 3.967 DEBUG Read Ok(286) bytes
server  | 3.967 DEBUG 0-RTT: no token, no 0-RTT
server  | 3.968 DEBUG Read secret available for Handshake: SymKey [32]: c08c0ffd33872ce53b38a28b2946d24432abf22d58444e235e3fc5f5b1d74de1
server  | 3.968 DEBUG Write secret available for Handshake: SymKey [32]: ed330ccce8da916a51955ee82cb0327f47c1f7683c9cf35be5e90f3ba12c5afa
server  | 3.968 DEBUG Writing transport parameters, msg=8
server  | 3.968 DEBUG Read secret available for ApplicationData: SymKey [32]: 46fc8ace2b0dac0ea2e364a4a890132d117a9e3d0c9644a5f1f44d2cd900fe81
server  | 3.968 DEBUG Write secret available for ApplicationData: SymKey [32]: d370d0e087a2e8dce387532f4d0afc13b9da18f845e56116f2a4f2594e01731a
server  | 3.968 DEBUG [Agent 0x55e52cd431d0] state -> InProgress
server  | 3.968 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 3.968 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 3.968 DEBUG [Crypto] Handshake keys installed
server  | 3.968 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 3.968 DEBUG [Crypto] Application write key installed
server  | 3.968 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 452, tv_nsec: 390518130 }
server  | 3.968 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:38975] Make permanent
server  | 3.968 DEBUG [unv-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] set as primary path
server  | 3.968 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] State change from WaitInitial -> Handshaking
server  | 3.968 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.968 DEBUG Building Initial dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.968 DEBUG CRYPTO for in offset=0, len=90
server  | 3.968 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=0 type=Initial pri-unv-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 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: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 3.968 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.968 DEBUG CRYPTO for hs offset=0, len=1037
server  | 3.968 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=0 type=Handshake pri-unv-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 0, len: 1037 }
server  | 3.968 DEBUG packet_sent this=0x55e52cd42b40, pn=0, ps=1085
server  | 3.968 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 3.968 DEBUG packet_sent this=0x55e52cd42b40, pn=0, ps=147
server  | 3.968 DEBUG ECN probing: sent 1 probes
server  | 3.968 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.968 DEBUG Building Initial dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.968 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.968 DEBUG CRYPTO for hs offset=1037, len=1183
server  | 3.969 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=1 type=Handshake pri-unv-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1037, len: 1183 }
server  | 3.969 DEBUG packet_sent this=0x55e52cd42b40, pn=1, ps=1232
server  | 3.969 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 3.969 DEBUG ECN probing: sent 2 probes
server  | 3.969 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false }
server  | 3.969 DEBUG Building Initial dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.969 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.969 DEBUG CRYPTO for hs offset=2220, len=1087
server  | 3.969 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=2 type=Handshake pri-unv-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1136
server  |   TX -> Crypto { offset: 2220, len: 1087 }
server  | 3.969 DEBUG packet_sent this=0x55e52cd42b40, pn=2, ps=1136
server  | 3.969 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 3.969 DEBUG ECN probing: sent 3 probes
server  | 3.969 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 3.969 DEBUG Building Initial dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.969 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.969 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 3.969 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 3.969 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 29.994864771s
server  | 3.969 DEBUG Setting timeout of 29.994864771s
server  | 3.969 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 3.969 DEBUG Building Initial dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.969 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 3.969 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 3.969 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 3.969 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 29.994827581s
server  | 3.969 DEBUG Setting timeout of 29.994827581s
sim     | Dropping packet 2 (1200 bytes) from 193.167.0.100
sim     | Dropping packet 3 (52 bytes) from 193.167.0.100
sim     | Dropping packet 4 (52 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  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Drop packet number space in
server  | 4.898 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 4.898 INFO [pri-unv-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] discarding a packet without an RTT estimate; guessing RTT=934.040863ms
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=6 type=Handshake pri-unv-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 47
server  |   -> RX Ping
server  |   -> RX Padding { len: 2 }
server  | 4.898 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 453, tv_nsec: 324558993 }
server  | 4.898 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 453, tv_nsec: 324558993 }
server  | 4.898 DEBUG [pri-unv-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 324558993 }
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.898 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.898 DEBUG CRYPTO for hs offset=0, len=1176
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=3 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 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: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 1176 }
server  | 4.898 DEBUG packet_sent this=0x55e52cd42b40, pn=3, ps=1232
server  | 4.898 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.898 DEBUG ECN probing: sent 4 probes
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.898 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.898 DEBUG CRYPTO for hs offset=1176, len=1183
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=4 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1176, len: 1183 }
server  | 4.898 DEBUG packet_sent this=0x55e52cd42b40, pn=4, ps=1232
server  | 4.898 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.898 DEBUG ECN probing: sent 5 probes
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.898 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.898 DEBUG CRYPTO for hs offset=2359, len=1183
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=5 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 2359, len: 1183 }
server  | 4.898 DEBUG packet_sent this=0x55e52cd42b40, pn=5, ps=1232
server  | 4.898 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.898 DEBUG ECN probing: sent 6 probes
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.898 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.898 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.898 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 46.409256ms
server  | 4.898 DEBUG Setting timeout of 46.409256ms
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=7 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 47
server  |   -> RX Ping
server  |   -> RX Padding { len: 2 }
server  | 4.898 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 453, tv_nsec: 324949290 }
server  | 4.898 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 453, tv_nsec: 324949290 }
server  | 4.898 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 324949290 }
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.898 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=6 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 4.898 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.898 DEBUG ECN probing: sent 7 probes
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.898 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.898 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.898 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 46.584424ms
server  | 4.898 DEBUG Setting timeout of 46.584424ms
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.898 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.898 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.898 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.898 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 46.555701ms
server  | 4.898 DEBUG Setting timeout of 46.555701ms
server  | 4.929 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=8 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 8, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  | 4.929 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=3]
server  | 4.929 DEBUG [LossRecovery] ACK for Handshake - largest_acked=3
server  | 4.929 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=2464, cwnd=12320, state=SlowStart, new_acked=4685
server  | 4.929 DEBUG Acked crypto frame space=hs offset=0 length=1176
server  | 4.929 DEBUG Acked crypto frame space=hs offset=2220 length=1087
server  | 4.929 DEBUG Acked crypto frame space=hs offset=1037 length=1183
server  | 4.929 DEBUG Acked crypto frame space=hs offset=0 length=1037
server  | 4.929 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 356380701 }
server  | 4.929 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.929 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.929 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.929 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.929 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 9.631762ms
server  | 4.929 DEBUG Setting timeout of 9.631762ms
server  | 4.929 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.929 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.929 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.930 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.930 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 9.56101ms
server  | 4.930 DEBUG Setting timeout of 9.56101ms
server  | 4.930 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=9 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 2, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) }
server  | 4.930 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=4]
server  | 4.930 DEBUG [LossRecovery] ACK for Handshake - largest_acked=4
server  | 4.930 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232
server  | 4.930 DEBUG Acked crypto frame space=hs offset=1176 length=1183
server  | 4.930 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 357294974 }
server  | 4.930 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.930 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.930 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.930 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.930 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 3.787763ms
server  | 4.930 DEBUG Setting timeout of 3.787763ms
server  | 4.930 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.930 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.930 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.930 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.930 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 3.733943ms
server  | 4.930 DEBUG Setting timeout of 3.733943ms
server  | 4.931 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=10 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 2, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) }
server  | 4.931 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=5]
server  | 4.931 DEBUG [LossRecovery] ACK for Handshake - largest_acked=5
server  | 4.931 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 4.931 DEBUG Acked crypto frame space=hs offset=2359 length=1183
server  | 4.931 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 358296900 }
server  | 4.931 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.931 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.931 DEBUG CRYPTO for hs offset=3542, len=1183
server  | 4.931 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=7 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 3542, len: 1183 }
server  | 4.931 DEBUG packet_sent this=0x55e52cd42b40, pn=7, ps=1232
server  | 4.931 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.931 DEBUG ECN probing: sent 8 probes
server  | 4.931 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.931 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.931 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.931 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.931 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 30.238072ms
server  | 4.931 DEBUG Setting timeout of 30.238072ms
server  | 4.931 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.931 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.931 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.931 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.931 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 30.209819ms
server  | 4.931 DEBUG Setting timeout of 30.209819ms
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.963 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.963 DEBUG CRYPTO for hs offset=4725, len=1183
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=8 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 4725, len: 1183 }
server  | 4.963 DEBUG packet_sent this=0x55e52cd42b40, pn=8, ps=1232
server  | 4.963 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.963 DEBUG ECN probing: sent 9 probes
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.963 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.963 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.963 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 30.834374ms
server  | 4.963 DEBUG Setting timeout of 30.834374ms
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.963 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.963 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.963 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 30.803627ms
server  | 4.963 DEBUG Setting timeout of 30.803627ms
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=11 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 3, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=7]
server  | 4.963 DEBUG [LossRecovery] ACK for Handshake - largest_acked=7
server  | 4.963 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232
server  | 4.963 DEBUG Acked crypto frame space=hs offset=3542 length=1183
server  | 4.963 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 389777780 }
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.963 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.963 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.963 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 26.999603ms
server  | 4.963 DEBUG Setting timeout of 26.999603ms
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.963 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.963 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.963 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.963 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 26.916929ms
server  | 4.963 DEBUG Setting timeout of 26.916929ms
server  | 4.991 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.991 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.991 DEBUG CRYPTO for hs offset=5908, len=1183
server  | 4.991 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=9 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 5908, len: 1183 }
server  | 4.991 DEBUG packet_sent this=0x55e52cd42b40, pn=9, ps=1232
server  | 4.991 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.991 DEBUG ECN probing: sent 10 probes
server  | 4.991 DEBUG ECN probing concluded with 10 probes sent
server  | 4.991 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.991 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.991 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.991 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.991 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 26.797723ms
server  | 4.991 DEBUG Setting timeout of 26.797723ms
server  | 4.991 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.991 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.991 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.991 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.991 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 26.770292ms
server  | 4.991 DEBUG Setting timeout of 26.770292ms
server  | 4.994 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=12 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 4, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) }
server  | 4.994 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=8]
server  | 4.994 DEBUG [LossRecovery] ACK for Handshake - largest_acked=8
server  | 4.994 INFO ECN validation succeeded, path is capable
server  | 4.994 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232
server  | 4.994 DEBUG Acked crypto frame space=hs offset=4725 length=1183
server  | 4.994 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 421057586 }
server  | 4.994 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.994 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.994 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.994 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.994 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 20.60635ms
server  | 4.994 DEBUG Setting timeout of 20.60635ms
server  | 4.994 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.994 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 4.994 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 4.994 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.994 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 20.549033ms
server  | 4.994 DEBUG Setting timeout of 20.549033ms
server  | 5.016 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.016 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.016 DEBUG CRYPTO for hs offset=7091, len=1183
server  | 5.016 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=10 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 7091, len: 1183 }
server  | 5.016 DEBUG packet_sent this=0x55e52cd42b40, pn=10, ps=1232
server  | 5.016 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.016 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.016 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.016 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.016 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 23.129955ms
server  | 5.016 DEBUG Setting timeout of 23.129955ms
server  | 5.016 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.016 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.016 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.016 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 23.099939ms
server  | 5.016 DEBUG Setting timeout of 23.099939ms
server  | 5.023 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=13 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 2, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) }
server  | 5.023 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=9]
server  | 5.023 DEBUG [LossRecovery] ACK for Handshake - largest_acked=9
server  | 5.023 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232
server  | 5.023 DEBUG Acked crypto frame space=hs offset=5908 length=1183
server  | 5.023 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 449545724 }
server  | 5.023 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.023 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.023 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.023 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.023 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 14.154218ms
server  | 5.023 DEBUG Setting timeout of 14.154218ms
server  | 5.023 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.023 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.023 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.023 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.023 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 14.086292ms
server  | 5.023 DEBUG Setting timeout of 14.086292ms
server  | 5.038 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.038 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.038 DEBUG CRYPTO for hs offset=8274, len=1183
server  | 5.038 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=11 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 8274, len: 1183 }
server  | 5.038 DEBUG packet_sent this=0x55e52cd42b40, pn=11, ps=1232
server  | 5.038 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.038 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.038 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.038 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.038 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.038 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 20.730347ms
server  | 5.038 DEBUG Setting timeout of 20.730347ms
server  | 5.038 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.038 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.038 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.038 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.038 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 20.700331ms
server  | 5.038 DEBUG Setting timeout of 20.700331ms
server  | 5.048 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=14 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 4, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 11, Ce: 0})) }
server  | 5.048 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=10]
server  | 5.048 DEBUG [LossRecovery] ACK for Handshake - largest_acked=10
server  | 5.048 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232
server  | 5.048 DEBUG Acked crypto frame space=hs offset=7091 length=1183
server  | 5.048 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 474736589 }
server  | 5.048 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.048 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.048 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.048 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.048 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 8.393056ms
server  | 5.048 DEBUG Setting timeout of 8.393056ms
server  | 5.048 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.048 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.048 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.048 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.048 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 8.313588ms
server  | 5.048 DEBUG Setting timeout of 8.313588ms
server  | 5.057 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.057 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.057 DEBUG CRYPTO for hs offset=9457, len=519
server  | 5.057 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=12 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 568
server  |   TX -> Crypto { offset: 9457, len: 519 }
server  | 5.057 DEBUG packet_sent this=0x55e52cd42b40, pn=12, ps=568
server  | 5.057 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.057 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.057 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.057 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.057 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.057 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 7.937923ms
server  | 5.057 DEBUG Setting timeout of 7.937923ms
server  | 5.057 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.057 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.057 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.057 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.057 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 7.914078ms
server  | 5.057 DEBUG Setting timeout of 7.914078ms
server  | 5.066 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.066 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.066 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.066 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.066 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 2.497800281s
server  | 5.066 DEBUG Setting timeout of 2.497800281s
server  | 5.066 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.066 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.066 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.066 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.066 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 2.497764655s
server  | 5.066 DEBUG Setting timeout of 2.497764655s
server  | 5.069 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=15 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   -> RX Ack { largest_acknowledged: 11, ack_delay: 3, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
server  | 5.069 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=11]
server  | 5.069 DEBUG [LossRecovery] ACK for Handshake - largest_acked=11
server  | 5.069 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=568, cwnd=12320, state=SlowStart, new_acked=1232
server  | 5.069 DEBUG Acked crypto frame space=hs offset=8274 length=1183
server  | 5.069 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 496269892 }
server  | 5.069 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.069 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.069 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.069 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.069 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 2.274830722s
server  | 5.069 DEBUG Setting timeout of 2.274830722s
server  | 5.069 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.069 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.069 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.069 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.069 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 2.27474934s
server  | 5.069 DEBUG Setting timeout of 2.27474934s
server  | 5.088 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=16 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 91
server  |   -> RX Ack { largest_acknowledged: 12, ack_delay: 26, first_ack_range: 12, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 13, Ce: 0})) }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 5.088 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=hs, ranges=[0..=12]
server  | 5.088 DEBUG [LossRecovery] ACK for Handshake - largest_acked=12
server  | 5.088 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=568
server  | 5.088 DEBUG Acked crypto frame space=hs offset=9457 length=519
server  | 5.088 DEBUG Read Ok(36) bytes
server  | 5.088 DEBUG [Agent 0x55e52cd431d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 5.088 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] TLS connection complete
server  | 5.088 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 515251413 }
server  | 5.088 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 5.089 DEBUG [Crypto] application read keys installed
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] State change from Handshaking -> Connected
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] State change from Connected -> Confirmed
server  | 5.089 DEBUG PMTUD started with probe size 1380
server  | 5.089 INFO [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Connection established
server  | 5.089 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 453, tv_nsec: 515251413 }
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.089 DEBUG Building Handshake dcid Some(CID [8]: a6c48224bc43d49f) scid Some(CID [10]: 59a64be964f1e2e42486)
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=13 type=Handshake pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 52
server  |   TX -> Ack { largest_acknowledged: 16, ack_delay: 0, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 11, Ce: 0})) }
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Drop packet number space hs
server  | 5.089 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 5.089 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=0 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [120, 108, 100, 236, 23, 35, 226, 69, 184, 244], stateless_reset_token: [225, 174, 95, 52, 76, 27, 110, 198, 77, 43, 120, 111, 111, 85, 150, 127] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [168, 26, 88, 241, 124, 104, 228, 121, 102, 219], stateless_reset_token: [183, 169, 67, 90, 0, 21, 60, 68, 13, 224, 51, 40, 228, 38, 107, 20] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [98, 36, 16, 228, 127, 117, 111, 55, 155, 192], stateless_reset_token: [79, 190, 28, 105, 100, 104, 42, 62, 217, 165, 9, 251, 111, 190, 154, 224] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [187, 42, 125, 184, 100, 248, 102, 154, 62, 245], stateless_reset_token: [134, 2, 21, 16, 153, 223, 43, 227, 254, 25, 117, 255, 158, 159, 194, 95] }
server  |   TX -> Padding { len: 1133 }
server  | 5.089 DEBUG packet_sent this=0x55e52cd42b40, pn=0, ps=1280
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.089 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.089 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=1 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 5.089 DEBUG packet_sent this=0x55e52cd42b40, pn=1, ps=1332
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.089 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.089 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 15.090175ms
server  | 5.089 DEBUG Setting timeout of 15.090175ms
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=0 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 224
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [124, 211, 213, 100, 102, 67, 208, 92], stateless_reset_token: [164, 174, 36, 60, 148, 76, 190, 86, 53, 163, 36, 201, 7, 205, 147, 40] }
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [243, 212, 230, 108, 116, 48, 188, 13], stateless_reset_token: [143, 36, 95, 161, 174, 99, 19, 192, 114, 7, 69, 198, 108, 3, 125, 188] }
server  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [82, 25, 188, 3, 34, 100, 80, 196], stateless_reset_token: [217, 19, 86, 230, 136, 202, 31, 38, 89, 43, 77, 24, 176, 122, 87, 134] }
server  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [246, 97, 201, 192, 170, 170, 105, 236], stateless_reset_token: [73, 194, 4, 230, 76, 208, 28, 169, 251, 138, 27, 109, 141, 80, 35, 220] }
server  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [114, 188, 190, 130, 85, 219, 247, 110], stateless_reset_token: [151, 182, 131, 221, 69, 153, 161, 32, 177, 213, 218, 25, 138, 151, 2, 148] }
server  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [148, 74, 219, 136, 233, 193, 101, 127], stateless_reset_token: [76, 249, 213, 60, 9, 86, 252, 119, 137, 35, 220, 215, 143, 215, 247, 78] }
server  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [84, 56, 19, 162, 52, 49, 202, 177], stateless_reset_token: [99, 72, 96, 48, 22, 20, 225, 148, 113, 119, 222, 223, 100, 203, 101, 254] }
server  | 5.089 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 453, tv_nsec: 535807759 }
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.089 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.089 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 15.062965ms
server  | 5.089 DEBUG Setting timeout of 15.062965ms
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=1 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 48
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 5.089 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 453, tv_nsec: 515879854 }
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.089 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=2 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 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: 0, Ect0: 2, Ce: 0})) }
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.089 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.089 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.089 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 15.085386ms
server  | 5.089 DEBUG Setting timeout of 15.085386ms
server  | 5.090 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] send session ticket 406f001413dddb8b2d04f9597c929061d29eb496fb275b7f0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a59a64be964f1e2e42486110c000000017aaa5a8a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 5.090 DEBUG Path = 'lqebgvlzlr'
server  | 5.090 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.090 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.090 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.090 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 14.052152ms
server  | 5.090 DEBUG Setting timeout of 14.052152ms
server  | 5.105 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.105 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.105 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=3 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false }
server  | 5.105 DEBUG packet_sent this=0x55e52cd42b40, pn=3, ps=1232
server  | 5.105 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.105 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.105 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.105 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 13.980485ms
server  | 5.105 DEBUG Setting timeout of 13.980485ms
server  | 5.105 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.105 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.105 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.105 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 13.951351ms
server  | 5.105 DEBUG Setting timeout of 13.951351ms
server  | 5.120 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 5.120 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.120 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=4 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false }
server  | 5.120 DEBUG packet_sent this=0x55e52cd42b40, pn=4, ps=1232
server  | 5.120 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.120 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.120 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.120 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 13.945653ms
server  | 5.120 DEBUG Setting timeout of 13.945653ms
server  | 5.120 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.120 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.120 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.120 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 13.918171ms
server  | 5.120 DEBUG Setting timeout of 13.918171ms
server  | 5.122 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=2 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 2, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
server  | 5.122 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=ap, ranges=[0..=2]
server  | 5.122 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 5.122 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=2464, cwnd=12320, state=SlowStart, new_acked=2612
server  | 5.122 DEBUG PMTUD probe of size 1380 succeeded
server  | 5.122 DEBUG PMTUD started with probe size 1420
server  | 5.122 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 5.122 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.122 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.122 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.122 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 12.327516ms
server  | 5.122 DEBUG Setting timeout of 12.327516ms
server  | 5.122 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.122 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.122 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.122 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 12.276982ms
server  | 5.122 DEBUG Setting timeout of 12.276982ms
server  | 5.135 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.135 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.135 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 5.135 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=5 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 2406, len: >>1341, fin: false }
server  | 5.135 DEBUG packet_sent this=0x55e52cd42b40, pn=5, ps=1372
server  | 5.135 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.135 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.135 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.135 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 14.085669ms
server  | 5.135 DEBUG Setting timeout of 14.085669ms
server  | 5.135 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.135 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.135 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.135 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 14.056664ms
server  | 5.135 DEBUG Setting timeout of 14.056664ms
server  | 5.150 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.150 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.150 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=6 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 0, offset: 3747, len: >>1302, fin: false }
server  | 5.150 DEBUG packet_sent this=0x55e52cd42b40, pn=6, ps=1332
server  | 5.150 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.150 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.150 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.150 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 13.438173ms
server  | 5.150 DEBUG Setting timeout of 13.438173ms
server  | 5.150 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.150 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.150 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.150 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 13.393119ms
server  | 5.150 DEBUG Setting timeout of 13.393119ms
server  | 5.152 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=3 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 2, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) }
server  | 5.152 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=ap, ranges=[0..=4]
server  | 5.152 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
server  | 5.152 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=2704, cwnd=12320, state=SlowStart, new_acked=2464
server  | 5.152 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.152 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.152 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.152 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 10.146572ms
server  | 5.152 DEBUG Setting timeout of 10.146572ms
server  | 5.152 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.152 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.152 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.152 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 10.088073ms
server  | 5.152 DEBUG Setting timeout of 10.088073ms
server  | 5.163 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.163 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.163 DEBUG CRYPTO for ap offset=0, len=321
server  | 5.163 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=7 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 473
server  |   TX -> Stream { stream_id: 0, offset: 5049, len: 71, fin: true }
server  |   TX -> Crypto { offset: 0, len: 321 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 85, 135, 246, 64, 56, 135, 76, 156, 62, 21, 52, 113, 61, 178, 219, 187, 108, 186, 164, 218, 212, 209, 66, 20, 181, 77, 219, 2, 122, 62, 132, 169, 205, 33, 83, 147] }
server  | 5.163 DEBUG packet_sent this=0x55e52cd42b40, pn=7, ps=473
server  | 5.163 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.163 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.163 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.163 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 3.485823ms
server  | 5.163 DEBUG Setting timeout of 3.485823ms
server  | 5.163 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.163 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.163 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 5.163 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 3.459865ms
server  | 5.163 DEBUG Setting timeout of 3.459865ms
server  | 5.168 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.168 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.168 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.168 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 1.695145818s
server  | 5.168 DEBUG Setting timeout of 1.695145818s
server  | 5.168 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.168 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.168 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.168 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 1.695120421s
server  | 5.168 DEBUG Setting timeout of 1.695120421s
server  | 5.182 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=4 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 2, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 5.182 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=ap, ranges=[0..=6]
server  | 5.182 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6
server  | 5.182 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=473, cwnd=12320, state=SlowStart, new_acked=2704
server  | 5.182 DEBUG PMTUD probe of size 1420 succeeded
server  | 5.182 DEBUG PMTUD started with probe size 1470
server  | 5.182 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 5.182 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 5.182 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.182 DEBUG Sending PMTUD probe of size 1470, count 1
server  | 5.182 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=8 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 1422
server  |   TX -> Ping
server  |   TX -> Padding { len: 1395 }
server  | 5.182 DEBUG packet_sent this=0x55e52cd42b40, pn=8, ps=1422
server  | 5.182 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 5.182 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.182 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 5.182 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 1.523153477s
server  | 5.182 DEBUG Setting timeout of 1.523153477s
server  | 5.182 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 5.182 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.182 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 5.182 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] delay duration 1.523120947s
server  | 5.182 DEBUG Setting timeout of 1.523120947s
server  | 5.194 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=5 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 39
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 17, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" }
server  | 5.194 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] Rx ACK space=ap, ranges=[0..=7]
server  | 5.194 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7
server  | 5.194 DEBUG on_packets_acked this=0x55e52cd42b40, limited=1, bytes_in_flight=1422, cwnd=12320, state=SlowStart, new_acked=473
server  | 5.194 DEBUG Acked crypto frame space=ap offset=0 length=321
server  | 5.194 INFO [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] ConnectionClose received. Error code: Application(0) frame type 0 reason 
server  | 5.194 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 457, tv_nsec: 706344170 } }
server  | 5.194 DEBUG [pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975] Path validated Instant { tv_sec: 453, tv_nsec: 620965752 }
server  | 5.194 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 5.194 DEBUG Building Short dcid Some(CID [8]: a6c48224bc43d49f)
server  | 5.194 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 453, tv_nsec: 620965752 }
server  | 5.194 DEBUG [Server 13dddb8b2d04f9597c929061d29eb496fb275b7f] pn=9 type=Short pri-path:a6c48224bc43d49f [::]:443->[::ffff:193.167.0.100]:38975 IpTos(Cs0, Ect0) len 38
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 5.194 DEBUG Setting timeout of 4.085225043s
server  | 5.194 DEBUG Setting timeout of 4.085211187s
server  | 5.213 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 457, tv_nsec: 706344170 } }; Total: 1
server  | 5.213 DEBUG Setting timeout of 4.066088212s
server  | 5.213 DEBUG Setting timeout of 4.066073515s

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 09:15:37,178 Using the client's key log file.
2025-04-30 09:15:37,191 
2025-04-30 09:15:37,192 Using the client's key log file.
2025-04-30 09:15:37,192 Using the client's key log file.
2025-04-30 09:15:37,202 
2025-04-30 09:15:37,203 Using the client's key log file.
2025-04-30 09:15:37,203 Using the client's key log file.
2025-04-30 09:15:37,857 Check of downloaded files succeeded.
2025-04-30 09:15:38,189 Server sent 9976 bytes in Handshake CRYPTO frames.
2025-04-30 09:15:38,572 Received a 1200 byte Initial packet from the client. Amplification limit: 3600
2025-04-30 09:15:38,572 Received a 1232 byte Handshake packet from the server. Total: 1232
2025-04-30 09:15:38,572 Received a 1232 byte Handshake packet from the server. Total: 2464
2025-04-30 09:15:38,572 Received a 1136 byte Handshake packet from the server. Total: 3600