2025-04-30 09:50:37,551 Generated random file: nqckscmhds of size: 5120
2025-04-30 09:50:37,551 Requests: https://server4:443/nqckscmhds
2025-04-30 09:50:37,932 
2025-04-30 09:50:37,932 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_et0ggllt/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_bgslll8x/ DOWNLOADS=/tmp/download_eix384vc/ SERVER_LOGS=/tmp/logs_server_4sh4i4mf CLIENT_LOGS=/tmp/logs_client_xq53_0c0 SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=us-central1-docker.pkg.dev/golang-interop-testing/quic/go-x-net:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/nqckscmhds"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 09:50:52,194  Container sim  Recreate
 Container sim  Recreated
 Container client  Recreate
 Container server  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
server  | Setting up routes...
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
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
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.xPvOEysatZ
server  | + mkdir -p /neqo/db
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
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.xPvOEysatZ
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.xPvOEysatZ -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  |             47:ef:56:cd:1f:be:db:a7:02:db:40:9e:18:0e:63:5f:
server  |             7b:9c:5f:8b
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:50:37 2025
server  |             Not After : Sat May 10 09:50:37 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:31:48:10:5b:11:83:b9:f4:df:8a:2a:db:92:06:ef:
server  |                     ee:6f:0b:84:1a:ee:25:c8:83:c5:3e:55:c4:e7:ee:55:
server  |                     42:73:8d:b0:d1:4d:65:98:09:33:6c:1a:8e:c3:fc:03:
server  |                     5b:85:3c:f2:37:1f:47:a6:ac:75:28:49:6f:42:25:53:
server  |                     9d
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: "WtEeTWxiSUUm1AqweyZEQTeoK4fiblppJyWJPuCWgj1YDZrJAZcUAo
server  |                 UpBVMqLpuJWLGDIi03g0Trqb2Aa77s7w891VKe39Cydh1cLyx4YzEYZEgGa0U
server  |                 Qajoo5vasI5418YcmpSHdTJIahmGLFROeUPZwoKSPNFTEEdUgUKZ85TvHnm2G
server  |                 M5CHTw7DOyNj1Ueo1Bi1jLH2JyWVtfussowqrzy7DJHBGoxUA5SMkARfAynWI
server  |                 0go7y6joMOOA6"
server  |             DNS name: "rkh4W0zlldbCrN0R6LVioLg9fVeJyu4RuygOhelaizqVMBgMgfBwgh
server  |                 1bM47vZjI0ogaff9YEXhdTqpYhbDTC3Lvu1OA9W5QDiVf3kh0wO0augiwOk6K
server  |                 JQ6KIXciZsVeyR0PUTXzxymtRNivj7VJ0m1u6O4JbX98IH3cyDUpckMANnv1S
server  |                 xtHxD2aoDDv17xcxleJP7lL94YTayE3vbvI3U6N3VrXBRSZ60C2y1SYTWtsBY
server  |                 041iQCdsnrvQu"
server  |             DNS name: "l7uMgMaZsnxORXbK3Ibi03JxPd89W1ysI3wh29SUrkqVb7pWHqPcrN
server  |                 Ezx3vkpCGjNIj47li53mL9ShRHwzfB22RHwiYC8q1O0rmtYXO6Nvon6ESfQca
server  |                 698tpQm0Yl8j8LsQiuEb1S00u2yAW3pybFbitJQ0FN3bWCdEdcS53E4NFaPoI
server  |                 T52WYxmiAWP5VhXD2AwO3OfqPUUWXfTDhNmD4npZNThgKDpRFYkQmW86bL4QJ
server  |                 tcz5bAZxoVHJD"
server  |             DNS name: "KUNKvVpWJmfL4kJP3hT3y1w2Ap6AyqUy7CbvoufInLnFH7M99OCw8Q
server  |                 JBrjcPuqxQOakzSHgPmq7jrPchRGmTSQNK3PeTvNSQl7hHZSj9GpK4mqP1ZnA
server  |                 uIG3Fd3GDXMqYF16rvOvB2Zr4AYmV2nyF4NPX903IiA0F0BmZ4tgLIcQOcdht
server  |                 xLyeafjn1jdPrQS52eK8iASOUykbYyGEh1DPgGYhTM7HLWhFKrW8kpCM6elqo
server  |                 y5mAEjnYT07i3"
server  |             DNS name: "NBFv5Gt59shtrXOAnoSLBQHFQfYxp2MuTVlOxowKWgrqIjU5IkJEqA
server  |                 sdwO8nllAn7b9ORRMOOwrsDf2KCDgaC4i37CobjeOMPsCUzmNy9jeo59HzHb9
server  |                 jjvzy48Uxcex3aVzeq1vEHrVIcTTQ3eXTtuIZocXO6lfscn8FODsR22w2ZN1v
server  |                 qjXMlaFW0yuZMAgVDJVa9hgdEDII8Ni9zZw8WE6lXCQ6oa32X1BiSuGKaJxAp
server  |                 bL6ixJOXfaqTA"
server  |             DNS name: "mH4NWdHq5WBlvqxTOTKLCDE1UU8IJdiKhNvi7exiW8uKDQp7N6kGdW
server  |                 NmDC9UiIwNxq7StNQHGTnQGjSdAAFM0KD4gBh2itan9YGBBBTOeEJ0JU1Nbu9
server  |                 g8NvP0ZSXsvaMyK3mI1iDsqGn5TtQCO5GHD3wY8scgVeAZREpV8MRZgpFVMBc
server  |                 hLFmw5Ilv8iH1ecH2GMkiElIv1MsVEwh7uUTlL7liaI1RGWjnadb41SQNze5g
server  |                 gbJMWU6e1OzTL"
server  |             DNS name: "DTo1HsvXxUzZo8UTsWnTfpYAZ2NxXCRPWLmXHfh8RgiYgMlHlgUENw
server  |                 GcGAH0659sr41WDKY9tfcRx5QwY2DkCVaAGZgGC2Gq5YOGvRC2tA5OunJLpuD
server  |                 XQpjmfgeDqOJFfNeCbsbNSAjseWmSON1hMSon5eiqAbhq9nAGd3pP2f5PA5kc
server  |                 ATz6hnzLGW9AfjbhaqfkEyXJaqMBtX7S8D1CNzne325pUDW5ivTd5UbQSYxHT
server  |                 dZNM0OrWM10ev"
server  |             DNS name: "ZXMf9pxdmW2Z1U5q1H3dfLV4ZVcIsxCsUhRhl43xWlS9Pq2pjuhYK0
server  |                 KXHtjbIEfgNljP6xpIzC2M4auZt1tldbj28Fk4YnM4bpj4zvvrydBxkCIsFir
server  |                 ZvcHaN7UIosRWsf4ayo2pmRuQfmSQLpJasWtLh9afI4LYuTUW9r1aUUkL8w2d
server  |                 c4B0yLtAkHHtOns8t5m4MkqUsWr3ewVSt2h6zZjWUBUwRjHGmBlVOVrRiyuMQ
server  |                 GTXHxtydTIz6j"
server  |             DNS name: "EcJCnU4wXvTFPB8ET8WNhGH4sOxpxycfIOyJhpjMyaWzr83w9FhWDV
server  |                 nMDHf9eS41GAbWxQsskuoYbnb06f2lpZ0Dntj0JGGuDGABXe7XYYigDxyzCVD
server  |                 HDouK3fVgj8LgNKKFhDoT2ydn8ubUIUBnMMiLnq1UWZkKuz6AWDrrysGyUpWS
server  |                 uyrzwaBR55OnK0igA4XWNGkI1OktAjEFDsJx3n1RGGZS0NN3ASRE4K4Gtjfxd
server  |                 gWFQBATM8EwXK"
server  |             DNS name: "61WOA0hdtPznNSUcvZoD3UZclLSjlw6KOhe7pKuqYIJceNWdU0bxLj
server  |                 lo6k9JgSHAgAMtL63MLeR3MGnMEzeJ3ax8LmxYyO2jM5wQ4Hl54OVthHyg01C
server  |                 ciUf1g22Ax0B6b5XceQAHTUSunGpgH38kbZQ99eMc8a6gc3exo6H61ZdmGpeS
server  |                 EWuhvJ5IuwX0euCavoomYflOinRZQBjCx3VP6vy8i6XfYfASRxmzoxqGIPqtx
server  |                 KZeTZpXVwVo4Q"
server  |             DNS name: "u9utHklI95J3Rdytgs8j21Jg2kgduuo0EUN2pAcpK3mYzA2iWyTGrR
server  |                 Kkvyftl5oIe2IQKk24zzbvuFJZxXB90Btiny4bpApNI8vbuA6wCQUi51VEwXP
server  |                 n12sTHZFTehliXjSwepmJbeNYGtDXL1nZH7DNR1w0pbOvFGNlegySoN2tk2UH
server  |                 7bNdXgK7ab61XX8xjK5RlySUE02R5wy8T7b1rQVO1npd6RavFOg89bJ0b8Mmu
server  |                 SjP9rFGrp3MAu"
server  |             DNS name: "6a7KDuh5a2EsFfCmxLJaQl9A51b9JzwpArwBMzPCulhETE0q3ht5OD
server  |                 HZKwYnxRMzjHMebLjATOWqYAtJ4oBAjkCnFHSu85jOWOnNzjtj0UO5HDZ4GkV
server  |                 VxIImpzXZoBlWYW9VA0vwEDdYr6rtlOC6KRujx44nBa3xorm9NV4n0j95xZUJ
server  |                 g8iyqZS5L39JZHIn9D4gwuXp5G0q0GfEuX6ip4cZeFrbuwbjV5rILGIG4grM9
server  |                 eCBaGIvfYAYgL"
server  |             DNS name: "gF5xeKLSbzva0TQl091FlO6IGcHK519WCE2qOkvAwjMl0n8f56sfzg
server  |                 6aLPpZUPa1eWS5trgVlmBq4tVnTdbekV3Y5tNMwd86puh0hwHVqb7TPJKNxKS
server  |                 MF1B6sApudL7EtCU0asImjKhJzI8hjKjZO1DLH4TR4bTgStsSOG53m3w3CYVe
server  |                 m9wd5RXQml65xXiqbbpp7WK5yHahNa8tbmuv4nKtZlNDQ7IbpRJShLSCMyey3
server  |                 rft47YWPErV0Y"
server  |             DNS name: "9na5awt0DsrALdHjHQvMWjrbUnOFXZPHHudcNhOU7hsOV7fC7DyO6n
server  |                 NEcZcoQvyjoEnf7WxwKVvr63bXKoUa6R8gAb3AMOAzDnr4DIsYaNsHvcT2kNV
server  |                 YGXfw48gr3YtI48rT3MupXE6zB0QRMTmEcHfplGAqASdZrfFeGXztTPZDXNtF
server  |                 Ae0U5OWD80hLZARN5iNOHmuVCvW6Rd43TNkvcSSDG3IlVtL5H77yNOnesxdJ4
server  |                 YM0HC10IbkrBm"
server  |             DNS name: "tI1GJrqh15WPaU05vAvfPR9AW00ychSo55C2o5vb1S7DRGzUk1aBcL
server  |                 nfqeaPbDMJbpFWLQ9NQZBgzHMGHkGLqG7MsTukzwb4Mq2BBtP3XI8Vt1aSlrU
server  |                 oQW4hybhND3A6JPtdc4KpqpLaWJJi9siBvWe753OHR3PqC8RpQ7NWO6z0H5wC
server  |                 pV69wErh78ShaXSH02jox2dkUtk9XE4BwRkJZB866CDAjfiVhAf05boyvxnjW
server  |                 bzYpwYhwIHk2E"
server  |             DNS name: "H9YxgsTg4QFK9tncNL8jyyLTPUSaUAaDGN9voTvRwA8VGRJVMBy3uL
server  |                 8VOxo5iNLyMMhq2PHiLCaDG8b4KGLfHKi13vA9WLzbetodhl7O2FB8oVMAEip
server  |                 dSDdKRtV9vGGQLQ2b3d7PzfdL4CIUq4Bg8m5L4mUTko9FkQqTID6xanAyRLn4
server  |                 ErEQMieHhxfcX3FOhmf7Erqadtzxm7N18mn3TT7F3CexSuL6lnoMzSou5HxZe
server  |                 bCcOZNoO7rQ4f"
server  |             DNS name: "QjjMlqZYEW5zoZl8mT47SrpA84OPIb402cIpLXbN8G9psB5mJ3oHu8
server  |                 EGqVuI5fIghtucpClb3YFpODnmRLscAJhTeWJHlkU76T0hV6sOTZqVYl0u0tw
server  |                 WCgH7Y0a9G7l2x8X35oRQgZ0adm2HKA7BTESo2uQOgVZYkj2hyUyrFo2y4uK1
server  |                 0GqlsFK9NuOPWHD88qRVW5bL4bYNxxs26pwMFTTBUCz7uPVPsTYPkHFsmziuT
server  |                 pch4CT5XxDeKt"
server  |             DNS name: "LVeyBpEKTl1YDq0a1cpEBjrMuRbGgFOxYwUhJyeEyM2popNhDPuluP
server  |                 iNxqpjqaeAlmpgTM9tfZ7nlkUv1LtQ7iLVC7FqDRwCchgT3dHdFl1DGqNbSnA
server  |                 zJwwutVR9SvfsCmGxTDgMKQrBK2RFV45oU8zJvRaDUMNvEX67x5odNrkobEnu
server  |                 xUQaPjDwkr9qe1FB7ZSbwzuE2IuGxWUH7BxGzehXcy1qLb1WBgqHvfYEQVAwR
server  |                 tYt2pqMY1cNdt"
server  |             DNS name: "YKLStRfK0X9yKu6eCVb5hBBBUJrzfVwpjlYeP8IPczzEmo4iPewRjd
server  |                 1xyr79n53bBg0ODWHMp1weaY01Drnt76QPycAMxuJdlbuBqGLWyZP8BFsCb51
server  |                 ztXTHdp8Dahxjn2wIwujk8TnTFffsD3D7jZxu0tPntrWrbhOpFxNwBsiQHJCP
server  |                 pJt82Uj0Dy2quP0XTlUbNTkxECrZyArTQNfJPVpSyHP22Qh1RRXvOQpAntmqD
server  |                 GnCdvBFnDgeZs"
server  |             DNS name: "TG5eVEnz1SzYlsxKDwWHrkJWY2OShdz9dqZ5BaMBPQ42UuNs6QkjDY
server  |                 qhgCN2ztbNUQgnGz9zm7KMErv8Vwa4McHoRNw4Rjb4afamPjpWbQhtAxuJzdI
server  |                 WheVnNRXVEvX8H8Y9y0awTUVx2K9sv6yi6qVyWveYh8owt2lx1MtlRAdL8t1q
server  |                 Qf8Bj524INlNzSrSpB3L1FuzwGxqcLZ9c6pQjEw30XdPvzqFUXEnFukRhJxo7
server  |                 hqr5jed0yvziA"
server  | 
server  |             Name: Certificate Subject Key ID
server  |             Data:
server  |                 e4:21:33:e8:18:ba:51:60:d2:25:93:d8:3f:90:26:d0:
server  |                 4d:8c:85:06
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 1b:0d:2e:ba:d5:ec:ae:6e:7f:a1:4e:11:c8:de:6a:15:
server  |                 51:96:d1:17
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:45:02:21:00:94:e3:42:cc:27:7e:03:54:ae:97:f2:
server  |         4f:72:19:ae:ad:d3:de:4f:fd:d8:33:b7:ed:de:29:34:
server  |         74:d6:1f:b6:33:02:20:50:b7:e5:1f:1c:31:5d:9e:aa:
server  |         16:80:d0:c8:c8:dd:da:59:d6:04:3c:27:48:34:01:81:
server  |         55:87:2b:e1:4b:bc:d0
server  |     Fingerprint (SHA-256):
server  |         A1:17:15:39:5B:9D:71:F5:4D:B4:1C:09:6E:F5:A8:62:14:87:E2:10:C2:CE:BF:51:80:4D:F3:C0:32:19:02:B8
server  |     Fingerprint (SHA1):
server  |         F3:AD:30:2A:0A:31:63:96:4D:9B:A6:2F:AC:6F:BD:F9:AB:61:86:23
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.730 DEBUG [Server] Unsupported version: 57414954
server  | 0.730 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52793 IpTos(Cs0, NotEct) len 15
server  | 0.731 DEBUG [Server] Unsupported version: 57414954
server  | 0.731 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52793 IpTos(Cs0, NotEct) len 15
server  | 0.731 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.731 DEBUG [Server] Unsupported version: 57414954
server  | 0.731 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52793 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.018948325s
server  | 0.731 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  | 2025/04/30 09:50:39 listening on [::]:42996
server  | 0.972 DEBUG [Server] Handle initial
server  | 0.972 INFO AddressValidation: no token; accepting
server  | 0.972 INFO [Server] Accept connection CID [8]: 700a88fdd1f582c8
server  | 0.972 DEBUG Overwrite initial version Version1 ==> Version1
server  | 0.975 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 0.975 INFO [Server ...] Received valid Initial packet with scid CID [8]: 6fa8563971dad7e8 dcid CID [8]: 700a88fdd1f582c8
server  | 0.975 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=700a88fdd1f582c8
server  | 0.975 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.975 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.975 DEBUG [Server 700a88fdd1f582c8] State change from Init -> WaitInitial
server  | 0.975 DEBUG [Server 700a88fdd1f582c8] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 296
server  |   -> RX Crypto { offset: 0, len: 249 }
server  | 0.975 DEBUG Read Ok(249) bytes
server  | 0.975 DEBUG 0-RTT: no token, no 0-RTT
server  | 0.976 DEBUG Read secret available for Handshake: SymKey [32]: 285bbf6da691049bcfc98d716d902234fe54e6b2247d2f799913abf60097be68
server  | 0.976 DEBUG Write secret available for Handshake: SymKey [32]: 98759e25556efc750799b86eb96a187ff1c7f383194f578ff74f16f1b24b48c1
server  | 0.976 DEBUG Writing transport parameters, msg=8
server  | 0.976 DEBUG Read secret available for ApplicationData: SymKey [32]: 654b9b99f0a5c13a3a619db44ca994b6c8fcdc8c4e18ac5a1a4359e783eed611
server  | 0.976 DEBUG Write secret available for ApplicationData: SymKey [32]: ac5dafdee47c59767a73a568ef78e9608f6d9cdd55b2c9adae82a7f5efe7db4a
server  | 0.976 DEBUG [Agent 0x56389ec521d0] state -> InProgress
server  | 0.976 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.976 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.976 DEBUG [Crypto] Handshake keys installed
server  | 0.976 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 0.976 DEBUG [Crypto] Application write key installed
server  | 0.976 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 383, tv_nsec: 642733573 }
server  | 0.976 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:42996] Make permanent
server  | 0.976 DEBUG [unv-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] set as primary path
server  | 0.976 DEBUG [Server 700a88fdd1f582c8] State change from WaitInitial -> Handshaking
server  | 0.976 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1
server  | 0.976 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.976 DEBUG Building Initial dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.976 DEBUG CRYPTO for in offset=0, len=90
server  | 0.976 DEBUG [Server 700a88fdd1f582c8] pn=0 type=Initial pri-unv-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 144
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 0.976 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.976 DEBUG CRYPTO for hs offset=0, len=1040
server  | 0.976 DEBUG [Server 700a88fdd1f582c8] pn=0 type=Handshake pri-unv-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 0, len: 1040 }
server  | 0.976 DEBUG packet_sent this=0x56389ec51b40, pn=0, ps=1088
server  | 0.976 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 0.976 DEBUG packet_sent this=0x56389ec51b40, pn=0, ps=144
server  | 0.976 DEBUG ECN probing: sent 1 probes
server  | 0.976 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.976 DEBUG Building Initial dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.976 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.976 DEBUG CRYPTO for hs offset=1040, len=1183
server  | 0.976 DEBUG [Server 700a88fdd1f582c8] pn=1 type=Handshake pri-unv-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1040, len: 1183 }
server  | 0.977 DEBUG packet_sent this=0x56389ec51b40, pn=1, ps=1232
server  | 0.977 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 0.977 DEBUG ECN probing: sent 2 probes
server  | 0.977 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false }
server  | 0.977 DEBUG Building Initial dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.977 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.977 DEBUG CRYPTO for hs offset=2223, len=1087
server  | 0.977 DEBUG [Server 700a88fdd1f582c8] pn=2 type=Handshake pri-unv-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1136
server  |   TX -> Crypto { offset: 2223, len: 1087 }
server  | 0.977 DEBUG packet_sent this=0x56389ec51b40, pn=2, ps=1136
server  | 0.977 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 0.977 DEBUG ECN probing: sent 3 probes
server  | 0.977 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.977 DEBUG Building Initial dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.977 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.977 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 0.977 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.977 DEBUG [Server 700a88fdd1f582c8] delay duration 29.995104304s
server  | 0.977 DEBUG Setting timeout of 29.995104304s
server  | 0.977 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.977 DEBUG Building Initial dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.977 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 0.977 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 0.977 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.977 DEBUG [Server 700a88fdd1f582c8] delay duration 29.995072605s
server  | 0.977 DEBUG Setting timeout of 29.995072605s
sim     | Dropping packet 2 (1200 bytes) from 193.167.0.100
sim     | Dropping packet 3 (49 bytes) from 193.167.0.100
sim     | Dropping packet 4 (49 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  | 2.923 DEBUG [Server 700a88fdd1f582c8] Drop packet number space in
server  | 2.923 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 2.923 INFO [pri-unv-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] discarding a packet without an RTT estimate; guessing RTT=1.951085745s
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] pn=6 type=Handshake pri-unv-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 47
server  |   -> RX Ping
server  |   -> RX Padding { len: 2 }
server  | 2.923 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 385, tv_nsec: 593819318 }
server  | 2.923 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 385, tv_nsec: 593819318 }
server  | 2.923 DEBUG [pri-unv-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 593819318 }
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.923 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.923 DEBUG CRYPTO for hs offset=0, len=1179
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] pn=3 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 1179 }
server  | 2.923 DEBUG packet_sent this=0x56389ec51b40, pn=3, ps=1232
server  | 2.923 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.923 DEBUG ECN probing: sent 4 probes
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.923 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.923 DEBUG CRYPTO for hs offset=1179, len=1183
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] pn=4 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1179, len: 1183 }
server  | 2.923 DEBUG packet_sent this=0x56389ec51b40, pn=4, ps=1232
server  | 2.923 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.923 DEBUG ECN probing: sent 5 probes
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.923 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.923 DEBUG CRYPTO for hs offset=2362, len=1183
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] pn=5 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 2362, len: 1183 }
server  | 2.923 DEBUG packet_sent this=0x56389ec51b40, pn=5, ps=1232
server  | 2.923 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.923 DEBUG ECN probing: sent 6 probes
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.923 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.923 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.923 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] delay duration 97.154407ms
server  | 2.923 DEBUG Setting timeout of 97.154407ms
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.923 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.923 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.923 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.923 DEBUG [Server 700a88fdd1f582c8] delay duration 97.117759ms
server  | 2.923 DEBUG Setting timeout of 97.117759ms
server  | 2.955 DEBUG [Server 700a88fdd1f582c8] pn=7 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 2.955 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=3]
server  | 2.955 DEBUG [LossRecovery] ACK for Handshake - largest_acked=3
server  | 2.955 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=2464, cwnd=12320, state=SlowStart, new_acked=4688
server  | 2.955 DEBUG Acked crypto frame space=hs offset=0 length=1179
server  | 2.955 DEBUG Acked crypto frame space=hs offset=2223 length=1087
server  | 2.955 DEBUG Acked crypto frame space=hs offset=1040 length=1183
server  | 2.955 DEBUG Acked crypto frame space=hs offset=0 length=1040
server  | 2.955 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 625610073 }
server  | 2.955 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.955 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.955 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.955 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.955 DEBUG [Server 700a88fdd1f582c8] delay duration 53.856963ms
server  | 2.955 DEBUG Setting timeout of 53.856963ms
server  | 2.955 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.955 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.955 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.955 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.955 DEBUG [Server 700a88fdd1f582c8] delay duration 53.759932ms
server  | 2.955 DEBUG Setting timeout of 53.759932ms
server  | 2.956 DEBUG [Server 700a88fdd1f582c8] pn=8 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: None }
server  | 2.956 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=4]
server  | 2.956 DEBUG [LossRecovery] ACK for Handshake - largest_acked=4
server  | 2.956 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232
server  | 2.956 DEBUG Acked crypto frame space=hs offset=1179 length=1183
server  | 2.956 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 626517593 }
server  | 2.956 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.956 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.956 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.956 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.956 DEBUG [Server 700a88fdd1f582c8] delay duration 42.491305ms
server  | 2.956 DEBUG Setting timeout of 42.491305ms
server  | 2.956 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.956 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.956 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.956 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.956 DEBUG [Server 700a88fdd1f582c8] delay duration 42.418509ms
server  | 2.956 DEBUG Setting timeout of 42.418509ms
server  | 2.957 DEBUG [Server 700a88fdd1f582c8] pn=9 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: None }
server  | 2.957 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=5]
server  | 2.957 DEBUG [LossRecovery] ACK for Handshake - largest_acked=5
server  | 2.957 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 2.957 DEBUG Acked crypto frame space=hs offset=2362 length=1183
server  | 2.957 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 627522578 }
server  | 2.957 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.957 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.957 DEBUG CRYPTO for hs offset=3545, len=1183
server  | 2.957 DEBUG [Server 700a88fdd1f582c8] pn=6 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 3545, len: 1183 }
server  | 2.957 DEBUG packet_sent this=0x56389ec51b40, pn=6, ps=1232
server  | 2.957 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.957 DEBUG ECN probing: sent 7 probes
server  | 2.957 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.957 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.957 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.957 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.957 DEBUG [Server 700a88fdd1f582c8] delay duration 65.784732ms
server  | 2.957 DEBUG Setting timeout of 65.784732ms
server  | 2.957 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.957 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.957 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.957 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.957 DEBUG [Server 700a88fdd1f582c8] delay duration 65.758233ms
server  | 2.957 DEBUG Setting timeout of 65.758233ms
server  | 2.988 DEBUG [Server 700a88fdd1f582c8] pn=10 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: None }
server  | 2.988 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=6]
server  | 2.988 DEBUG [LossRecovery] ACK for Handshake - largest_acked=6
server  | 2.988 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 2.988 DEBUG Acked crypto frame space=hs offset=3545 length=1183
server  | 2.988 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 659224479 }
server  | 2.988 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.988 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.988 DEBUG CRYPTO for hs offset=4728, len=1183
server  | 2.988 DEBUG [Server 700a88fdd1f582c8] pn=7 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 4728, len: 1183 }
server  | 2.988 DEBUG packet_sent this=0x56389ec51b40, pn=7, ps=1232
server  | 2.988 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.988 DEBUG ECN probing: sent 8 probes
server  | 2.988 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.988 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.988 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.988 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.988 DEBUG [Server 700a88fdd1f582c8] delay duration 57.714528ms
server  | 2.988 DEBUG Setting timeout of 57.714528ms
server  | 2.988 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.988 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 2.988 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 2.988 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.988 DEBUG [Server 700a88fdd1f582c8] delay duration 57.683089ms
server  | 2.988 DEBUG Setting timeout of 57.683089ms
server  | 3.020 DEBUG [Server 700a88fdd1f582c8] pn=11 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: None }
server  | 3.020 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=7]
server  | 3.020 DEBUG [LossRecovery] ACK for Handshake - largest_acked=7
server  | 3.020 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.020 DEBUG Acked crypto frame space=hs offset=4728 length=1183
server  | 3.020 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 690832235 }
server  | 3.020 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.020 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.020 DEBUG CRYPTO for hs offset=5911, len=1183
server  | 3.020 DEBUG [Server 700a88fdd1f582c8] pn=8 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 5911, len: 1183 }
server  | 3.020 DEBUG packet_sent this=0x56389ec51b40, pn=8, ps=1232
server  | 3.020 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.020 DEBUG ECN probing: sent 9 probes
server  | 3.020 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.020 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.020 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.020 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.020 DEBUG [Server 700a88fdd1f582c8] delay duration 50.693297ms
server  | 3.020 DEBUG Setting timeout of 50.693297ms
server  | 3.020 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.020 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.020 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.020 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.020 DEBUG [Server 700a88fdd1f582c8] delay duration 50.660696ms
server  | 3.020 DEBUG Setting timeout of 50.660696ms
server  | 3.052 DEBUG [Server 700a88fdd1f582c8] pn=12 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: None }
server  | 3.052 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=8]
server  | 3.052 DEBUG [LossRecovery] ACK for Handshake - largest_acked=8
server  | 3.052 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.052 DEBUG Acked crypto frame space=hs offset=5911 length=1183
server  | 3.052 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 722500193 }
server  | 3.052 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.052 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.052 DEBUG CRYPTO for hs offset=7094, len=1183
server  | 3.052 DEBUG [Server 700a88fdd1f582c8] pn=9 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 7094, len: 1183 }
server  | 3.052 DEBUG packet_sent this=0x56389ec51b40, pn=9, ps=1232
server  | 3.052 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.052 DEBUG ECN probing: sent 10 probes
server  | 3.052 DEBUG ECN probing concluded with 10 probes sent
server  | 3.052 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.052 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.052 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.052 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.052 DEBUG [Server 700a88fdd1f582c8] delay duration 44.488538ms
server  | 3.052 DEBUG Setting timeout of 44.488538ms
server  | 3.052 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.052 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.052 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.052 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.052 DEBUG [Server 700a88fdd1f582c8] delay duration 44.456759ms
server  | 3.052 DEBUG Setting timeout of 44.456759ms
server  | 3.083 DEBUG [Server 700a88fdd1f582c8] pn=13 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: None }
server  | 3.083 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=9]
server  | 3.083 DEBUG [LossRecovery] ACK for Handshake - largest_acked=9
server  | 3.083 WARN ECN validation failed, no ECN counts in ACK frame
server  | 3.083 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.083 DEBUG Acked crypto frame space=hs offset=7094 length=1183
server  | 3.083 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 754231982 }
server  | 3.083 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.083 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.083 DEBUG CRYPTO for hs offset=8277, len=1183
server  | 3.083 DEBUG [Server 700a88fdd1f582c8] pn=10 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Crypto { offset: 8277, len: 1183 }
server  | 3.083 DEBUG packet_sent this=0x56389ec51b40, pn=10, ps=1232
server  | 3.083 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.083 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.083 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.083 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.083 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.083 DEBUG [Server 700a88fdd1f582c8] delay duration 39.139838ms
server  | 3.083 DEBUG Setting timeout of 39.139838ms
server  | 3.083 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.083 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.083 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.083 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.083 DEBUG [Server 700a88fdd1f582c8] delay duration 39.110964ms
server  | 3.083 DEBUG Setting timeout of 39.110964ms
server  | 3.115 DEBUG [Server 700a88fdd1f582c8] pn=14 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 10, ack_ranges: [], ecn_count: None }
server  | 3.115 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=10]
server  | 3.115 DEBUG [LossRecovery] ACK for Handshake - largest_acked=10
server  | 3.115 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.115 DEBUG Acked crypto frame space=hs offset=8277 length=1183
server  | 3.115 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 785874872 }
server  | 3.115 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.115 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.115 DEBUG CRYPTO for hs offset=9460, len=508
server  | 3.115 DEBUG [Server 700a88fdd1f582c8] pn=11 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 557
server  |   TX -> Crypto { offset: 9460, len: 508 }
server  | 3.115 DEBUG packet_sent this=0x56389ec51b40, pn=11, ps=557
server  | 3.115 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.115 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.115 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.115 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.115 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.115 DEBUG [Server 700a88fdd1f582c8] delay duration 18.419346ms
server  | 3.115 DEBUG Setting timeout of 18.419346ms
server  | 3.115 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.115 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.115 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.115 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.115 DEBUG [Server 700a88fdd1f582c8] delay duration 18.387216ms
server  | 3.115 DEBUG Setting timeout of 18.387216ms
client  | 2025/04/30 09:50:41 connected to server4:443
server  | 3.135 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.135 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.135 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.135 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.135 DEBUG [Server 700a88fdd1f582c8] delay duration 4.800831388s
server  | 3.135 DEBUG Setting timeout of 4.800831388s
server  | 3.135 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.135 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.135 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.135 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.135 DEBUG [Server 700a88fdd1f582c8] delay duration 4.800799529s
server  | 3.135 DEBUG Setting timeout of 4.800799529s
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] pn=15 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 88
server  |   -> RX Ack { largest_acknowledged: 11, ack_delay: 0, first_ack_range: 11, ack_ranges: [], ecn_count: None }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=hs, ranges=[0..=11]
server  | 3.147 DEBUG [LossRecovery] ACK for Handshake - largest_acked=11
server  | 3.147 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=557
server  | 3.147 DEBUG Acked crypto frame space=hs offset=9460 length=508
server  | 3.147 DEBUG Read Ok(36) bytes
server  | 3.147 DEBUG [Agent 0x56389ec521d0] 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.147 DEBUG [Server 700a88fdd1f582c8] TLS connection complete
server  | 3.147 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 817813124 }
server  | 3.147 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 3.147 DEBUG [Crypto] application read keys installed
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] State change from Handshaking -> Connected
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] State change from Connected -> Confirmed
server  | 3.147 DEBUG PMTUD started with probe size 1380
server  | 3.147 INFO [Server 700a88fdd1f582c8] Connection established
server  | 3.147 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 385, tv_nsec: 817813124 }
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] pn=0 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 112
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [190, 204, 113, 139, 175, 150, 245, 114], stateless_reset_token: [207, 100, 178, 229, 79, 233, 116, 222, 6, 177, 212, 114, 175, 21, 74, 14] }
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [249, 96, 176, 109, 190, 135, 51, 76], stateless_reset_token: [6, 69, 154, 156, 242, 34, 137, 6, 107, 16, 207, 7, 89, 9, 112, 171] }
server  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [103, 225, 208, 104, 134, 55, 129, 70], stateless_reset_token: [142, 175, 174, 109, 1, 143, 87, 73, 87, 200, 127, 216, 1, 48, 227, 229] }
server  | 3.147 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 385, tv_nsec: 837813124 }
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.147 DEBUG Building Handshake dcid Some(CID [8]: 6fa8563971dad7e8) scid Some(CID [10]: fb4ceb332e165dc662f0)
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] pn=12 type=Handshake pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 49
server  |   TX -> Ack { largest_acknowledged: 15, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: None }
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] Drop packet number space hs
server  | 3.147 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 3.147 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] pn=0 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [209, 25, 221, 76, 155, 129, 26, 41, 223, 76], stateless_reset_token: [229, 202, 164, 136, 205, 164, 181, 124, 180, 114, 59, 254, 118, 46, 172, 230] }
server  |   TX -> Padding { len: 1226 }
server  | 3.147 DEBUG packet_sent this=0x56389ec51b40, pn=0, ps=1283
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.147 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.147 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] delay duration 16.793254ms
server  | 3.147 DEBUG Setting timeout of 16.793254ms
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] pn=1 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 48
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 3.147 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 385, tv_nsec: 818389337 }
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.147 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] pn=1 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 31
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.147 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.147 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.147 DEBUG [Server 700a88fdd1f582c8] delay duration 17.482343ms
server  | 3.147 DEBUG Setting timeout of 17.482343ms
server  | 3.148 DEBUG [Server 700a88fdd1f582c8] send session ticket 40630008700a88fdd1f582c80104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0afb4ceb332e165dc662f0110c000000016a3aba4a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 3.148 DEBUG Path = 'nqckscmhds'
server  | 3.148 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.148 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.148 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.148 DEBUG [Server 700a88fdd1f582c8] delay duration 16.507337ms
server  | 3.148 DEBUG Setting timeout of 16.507337ms
server  | 3.166 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.166 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.166 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 3.166 DEBUG [Server 700a88fdd1f582c8] pn=2 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 1332
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1303, fin: false }
server  | 3.166 DEBUG packet_sent this=0x56389ec51b40, pn=2, ps=1332
server  | 3.166 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.166 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.166 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.166 DEBUG [Server 700a88fdd1f582c8] delay duration 30.371543ms
server  | 3.166 DEBUG Setting timeout of 30.371543ms
server  | 3.166 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.166 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.166 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.166 DEBUG [Server 700a88fdd1f582c8] delay duration 30.348791ms
server  | 3.166 DEBUG Setting timeout of 30.348791ms
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.197 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] pn=3 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1303, len: >>1202, fin: false }
server  | 3.197 DEBUG packet_sent this=0x56389ec51b40, pn=3, ps=1232
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.197 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.197 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] delay duration 29.649699ms
server  | 3.197 DEBUG Setting timeout of 29.649699ms
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.197 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.197 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] delay duration 29.619803ms
server  | 3.197 DEBUG Setting timeout of 29.619803ms
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] pn=2 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 33
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None }
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=ap, ranges=[0..=2]
server  | 3.197 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 3.197 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=2615
server  | 3.197 DEBUG PMTUD probe of size 1380 succeeded
server  | 3.197 DEBUG PMTUD started with probe size 1420
server  | 3.197 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.197 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.197 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] delay duration 27.914395ms
server  | 3.197 DEBUG Setting timeout of 27.914395ms
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.197 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.197 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.197 DEBUG [Server 700a88fdd1f582c8] delay duration 27.838454ms
server  | 3.197 DEBUG Setting timeout of 27.838454ms
server  | 3.225 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 3.225 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.225 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 3.225 DEBUG [Server 700a88fdd1f582c8] pn=4 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 2505, len: >>1341, fin: false }
server  | 3.226 DEBUG packet_sent this=0x56389ec51b40, pn=4, ps=1372
server  | 3.226 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.226 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.226 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.226 DEBUG [Server 700a88fdd1f582c8] delay duration 28.912386ms
server  | 3.226 DEBUG Setting timeout of 28.912386ms
server  | 3.226 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.226 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.226 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.226 DEBUG [Server 700a88fdd1f582c8] delay duration 28.882089ms
server  | 3.226 DEBUG Setting timeout of 28.882089ms
server  | 3.253 DEBUG [Server 700a88fdd1f582c8] pn=3 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 34
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 3052, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 3.253 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=ap, ranges=[0..=3]
server  | 3.253 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
server  | 3.253 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=1372, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.253 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 3.253 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.253 DEBUG CRYPTO for ap offset=0, len=23
server  | 3.253 DEBUG [Server 700a88fdd1f582c8] pn=5 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 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.253 DEBUG packet_sent this=0x56389ec51b40, pn=5, ps=1332
server  | 3.253 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.253 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.253 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.253 DEBUG [Server 700a88fdd1f582c8] delay duration 24.228358ms
server  | 3.253 DEBUG Setting timeout of 24.228358ms
server  | 3.253 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.253 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.253 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.253 DEBUG [Server 700a88fdd1f582c8] delay duration 24.200646ms
server  | 3.253 DEBUG Setting timeout of 24.200646ms
client  | 2025/04/30 09:50:41 fetched https://server4:443/nqckscmhds
server  | 3.278 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 3.278 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.278 DEBUG CRYPTO for ap offset=23, len=282
server  | 3.278 DEBUG [Server 700a88fdd1f582c8] pn=6 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 357
server  |   TX -> Crypto { offset: 23, len: 282 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 170, 120, 39, 70, 126, 118, 147, 249, 181, 63, 16, 169, 191, 21, 131, 35, 215, 151, 80, 89, 51, 43, 183, 170, 92, 197, 90, 183, 136, 251, 241, 49, 129, 204, 191, 94] }
server  | 3.278 DEBUG packet_sent this=0x56389ec51b40, pn=6, ps=357
server  | 3.278 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.278 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.278 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.278 DEBUG [Server 700a88fdd1f582c8] delay duration 5.814084ms
server  | 3.278 DEBUG Setting timeout of 5.814084ms
server  | 3.278 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.278 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.278 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.278 DEBUG [Server 700a88fdd1f582c8] delay duration 5.78527ms
server  | 3.278 DEBUG Setting timeout of 5.78527ms
server  | 3.281 DEBUG [Server 700a88fdd1f582c8] pn=4 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 34
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 3024, first_ack_range: 4, ack_ranges: [], ecn_count: None }
server  | 3.281 DEBUG [Server 700a88fdd1f582c8] Rx ACK space=ap, ranges=[0..=4]
server  | 3.281 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
server  | 3.281 DEBUG on_packets_acked this=0x56389ec51b40, limited=1, bytes_in_flight=1689, cwnd=12320, state=SlowStart, new_acked=1372
server  | 3.281 DEBUG PMTUD probe of size 1420 succeeded
server  | 3.281 DEBUG PMTUD started with probe size 1470
server  | 3.281 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 3.281 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.281 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.281 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.281 DEBUG [Server 700a88fdd1f582c8] delay duration 2.621344ms
server  | 3.281 DEBUG Setting timeout of 2.621344ms
server  | 3.281 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.281 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.281 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.281 DEBUG [Server 700a88fdd1f582c8] delay duration 2.561082ms
server  | 3.281 DEBUG Setting timeout of 2.561082ms
server  | 3.284 DEBUG [Server 700a88fdd1f582c8] pn=5 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 32
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  | 3.284 INFO [Server 700a88fdd1f582c8] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 3.284 DEBUG [Server 700a88fdd1f582c8] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 395, tv_nsec: 439176200 } }
server  | 3.284 DEBUG [pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996] Path validated Instant { tv_sec: 385, tv_nsec: 955354422 }
server  | 3.284 DEBUG [Server 700a88fdd1f582c8] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 3.284 DEBUG Building Short dcid Some(CID [8]: 6fa8563971dad7e8)
server  | 3.284 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 385, tv_nsec: 955354422 }
server  | 3.284 DEBUG [Server 700a88fdd1f582c8] pn=7 type=Short pri-path:6fa8563971dad7e8 [::]:443->[::ffff:193.167.0.100]:42996 IpTos(Cs0, NotEct) len 35
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 3.284 DEBUG Setting timeout of 9.483737992s
server  | 3.284 DEBUG Setting timeout of 9.483726561s

client exited with code 0
Aborting on container exit...
 Container server  Stopping
 Container client  Stopping
 Container client  Stopped
 Container server  Stopped
 Container sim  Stopping
 Container sim  Stopped

2025-04-30 09:50:52,295 Using the client's key log file.
2025-04-30 09:50:52,307 
2025-04-30 09:50:52,308 Using the client's key log file.
2025-04-30 09:50:52,308 Using the client's key log file.
2025-04-30 09:50:52,318 
2025-04-30 09:50:52,319 Using the client's key log file.
2025-04-30 09:50:52,319 Using the client's key log file.
2025-04-30 09:50:52,969 Check of downloaded files succeeded.
2025-04-30 09:50:53,300 Server sent 9968 bytes in Handshake CRYPTO frames.
2025-04-30 09:50:53,663 Received a 1200 byte Initial packet from the client. Amplification limit: 3600
2025-04-30 09:50:53,663 Received a 1232 byte Handshake packet from the server. Total: 1232
2025-04-30 09:50:53,664 Received a 1232 byte Handshake packet from the server. Total: 2464
2025-04-30 09:50:53,664 Received a 1136 byte Handshake packet from the server. Total: 3600