2025-04-30 09:39:44,221 Generated random file: ynooxovzxw of size: 5120
2025-04-30 09:39:44,221 Requests: https://server4:443/ynooxovzxw
2025-04-30 09:39:44,600 
2025-04-30 09:39:44,601 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_z9dfpqfe/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_xi8f7n0k/ DOWNLOADS=/tmp/download_0gqb9o_t/ SERVER_LOGS=/tmp/logs_server_5jps7a79 CLIENT_LOGS=/tmp/logs_client_mdpdvz8i SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=martenseemann/quic-go-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/ynooxovzxw"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 09:39:57,082  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...
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
client  | Using commit: e4bb2dbd5557c9417b30cf9fcd9032fd6fea5366
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 10 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.LyTWbwKRcm
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.LyTWbwKRcm
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.LyTWbwKRcm -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  |             43:99:e4:7e:85:26:c7:1f:3e:a2:22:36:1d:ce:7c:45:
server  |             70:dd:39:76
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:39:44 2025
server  |             Not After : Sat May 10 09:39:44 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:87:5a:e0:cb:3d:10:8a:f2:0c:5d:b1:69:3a:94:aa:
server  |                     bc:6f:eb:ef:a7:35:9f:bd:8c:2e:ad:d9:71:00:c4:02:
server  |                     44:48:85:63:ac:08:b6:80:d5:a0:c3:60:d8:57:2b:df:
server  |                     48:15:dd:10:b5:6d:1f:09:d1:cc:fa:7b:92:fa:46:96:
server  |                     69
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: "LA7yjnpUHEwyuYPD9sEQWijgM3LCNZ8kdKNmTy66sTofnruWTh1Tdz
server  |                 Kg031eLmAPTOzblHjqMRallFqnYYfEg5f0LKcdW8ezeA1Ab8P5YE8RC3FXGMx
server  |                 nvOaaeIgYvmuYm8BUNSRgaIqo7nlxXqagLirv6hvKhIgZwkKsApukVnWWQ4rn
server  |                 yY1MfShwINV6WCvmnJMYAWqyoinlw9GJty3z0MzvFCfEH2icHL2PaswuL5vZO
server  |                 92N3KTG7AlZDO"
server  |             DNS name: "hD9txRZNamM0xLQyq16ltl0hLgDjoRpGOkrLKxdwbN8YR5HiqAvKD9
server  |                 XR8Uw7o5dOQBae9XKMzLAQLnwJ0sCYoOgIrxdtEFVbP7pVRzG9uUPQOJ9F4mi
server  |                 GGNHGAirtprBuGZPGGGoS5k4oiH6aPwMP8HMX89qflvZD5kv9cLDrZYTEjx39
server  |                 ZwbBm2QtlJXA0JSE9WsHBCTNAZ2FmVRVWtThFDh3gyl7gcHLv4OMzlW5HfSNX
server  |                 JN4ooYRgpa6JY"
server  |             DNS name: "e3wu3c79bXVhbY5qOHnqnCuWg8E3eUp26Z2GorlElbgjnia1Kvolkh
server  |                 8QWTSe113SrH5Cjw5hV9mLG4HDFH1RkDCCnsjtGwr0hLQHzQ28toDXNNbB5ro
server  |                 5v8ViJV3bqbuALJ1kfo8Z8B4KpG3kVEFnYusL7dU9HuJMOwoO9IDtX2yKGfRT
server  |                 iX81InUXz04Jrio9BMWBof7WHGjwDflvEmkXyIDX3epqVY0qC8a6qhIG5lkLj
server  |                 achCFCbOSUfJr"
server  |             DNS name: "VJnKHy4IijG191H3AZcmfh7kJ6oN44U6RVnK2L6LOpUZV4nRGmGpTN
server  |                 xuFTGVMWa7DXWncr38f7N2QbSAjAGmabOjH7YLZKNXSciPN7JL63dPTko2XFS
server  |                 KvrXe60pxgFgCYAxTbmodfMFAWSVl404j6zwZTzmx8PLbXLcjfuGr2RCudtPq
server  |                 gbl3NEYEvMdMSNQwcY22sll8XiAeDTzkMEc0gY0rtJr5zPP0yi76IaDpwYlJz
server  |                 SpZsuO2jp7jCJ"
server  |             DNS name: "kbSY2xPUEzqSKAjKL0XzEDtWRfvJgc1K2JydZy1KzWNtsgyWT1788A
server  |                 QrdtOUaDn74w5HSMWnvQIbvuRvt5tuHUr8cZcflZvcEVH8eTZni6tDIgeUZG7
server  |                 5qqE1o7ezN3J1EP1fviXjGRoOd8sPRBZLPh6cmzKITHagkzKB3O5xRmq4ZnuA
server  |                 TKyRS4O0j1ZDBxwdPNBgOFy0ETXs0MEcsXDx5S6t2PhTGRVDvF0Lbb9WKCI1a
server  |                 03YkN8orhKgkL"
server  |             DNS name: "VWFceVh8sQfWTbfyB4JjcFcUWEXKCdXSiXFNNtw6RJ4vdGCYXn4aGM
server  |                 p1ZIyzWe9sYbxO0Yhqc3b0m9eGqmZl8m6ZgykBgi73i9H0KkKuUCwJ1tTALCs
server  |                 MoGTMc77V1UUY3FU1Urw7lX9PYQ4RPLBWW7mU4ND1470EylPifpneGsYvbuW0
server  |                 yCulyHlOvi5pdpm2X8B9UrBIVlDmpJiKbvqSNeLlPnTKeqPct3Enh0mN7nViR
server  |                 3nMdw6R8NZCCl"
server  |             DNS name: "niOf3fWPqlpC5dIBmwoMyMcA5MWQ5zypoJtu5Q5cyvwmjMabawihfi
server  |                 ox6MydqbbQ951SpDed9EVbDbYRasYdmxVsJb39TU3tokPREvHwjLqrb9bVppr
server  |                 c3udLAhqWVn0ujtMQEyBJw15FeGCjoAKPbZJKVznZACQ0JKUPRByWW50nmrvs
server  |                 JUSIqHdOPHl6WIIi1V6Krr0ballGDMSGpcYr3b9bTrBSyLSM98YQB571tdDdw
server  |                 N0ehqVLsGgT6V"
server  |             DNS name: "Ou0JDhYSr6LoxOwyIYR1X6ljQKKdHQKGl8SYFLUoOo9Ph9tm5kj6Cs
server  |                 M7ZJ2q3oc2SLtVaNbZfC2G92upJikqxp0A0qejuSdlD9FSyb05tRlhd5VL7Rr
server  |                 QbUe3Ou6J5sxiPpSrnj05vG0DvArKI2rp5oBWqjGArsbwt9g8ntHrneXaaz56
server  |                 GDHU2H5PNEJAT1i8mbPF77AYlaN37BUqhkdgFUPtnPZDP4TR1rGFVjZea9Szh
server  |                 Z2aUredjlaCm0"
server  |             DNS name: "QXsHgJYBd4fQx66vxBShKAsJXybBH2lM2YNroOgA4jzGwg3H6NOnvl
server  |                 loZNR1iv0cOgvjKk6ZLAhsZSKmFyhAa9eIwbdH9dESRNzwP9gawAiPUuDc6Ow
server  |                 fnZRmA1wtYsIU1aCLRDnVyUbehsKiPNaidpBlK3cJFR18BUEuzaxlcq20V9qe
server  |                 6kzCcjObuvQbVXWJASb1xU9LkbYGSZVfh8ryC1Rln3XgKdybIH6p8dRlFNf6o
server  |                 7dW1LNcfRtdTf"
server  |             DNS name: "ckJhgZCNJMEVhlWE32LveRTTC0QMfArg6ppsC63DfIuhMP0bT7hviv
server  |                 FgTLpNzC5oZ8wXmu0AolfRHLK7R89YzVpYKLiXSm9Vk8W7ogR1D95TxunNLiK
server  |                 kVVUMBfONwOZcKiU757B2yy0rabjeBGjpW3evBHa6X48DWg60WRrLNzAhbRkD
server  |                 oeyWK6nSfbMNuC5HVEUVpbUrZPnlFBBcNhWHYF7AyIY10PO2MUub0MdAEq87c
server  |                 JI7TA5UNZanyj"
server  |             DNS name: "ssgW8LMAyNPCAQvNdLll3kxPJFLefl4Uc5UDZg826eeP1rcA0NQMSQ
server  |                 YmFmwy5lCK9WKeWq3bl1YQWj5IG5G9RAbVKKkeJuvQq6aMUA2CS2AwY8YxYdq
server  |                 Tj00tLVjPkZZKCSJqS8aJJzToDCRJY5fhIapSLpma8UIpJnxehNGAwt9rbvaj
server  |                 nJ1R33FPb3SActosq12jZHTH8Y1wp9e0UGXgTAez5wXJpkingrtEaGxgBGVHQ
server  |                 IKDuSaAg9dTUH"
server  |             DNS name: "Z7KB3bGjgrLchmeKmjP6saYO8ec9MBoN6omCvcc6Pn1JxRnIIYgHA5
server  |                 MY6QRcSUaHZA4DQTMLO5iBMcba5BAbpTS362ei2ZjgZeYNZYQiu6byGLCcIfD
server  |                 f4mu6DzrfsMpLKvtdMoiCHUMVWiEUelSH3CAYpKGJV1UiYCAfrVXnw5QAPXH5
server  |                 RRWBGowkh36fLaRS8WrnfkHQ5C039OLn5scK91aQ6unfyMO6gDiqKnyXSXu7C
server  |                 Koc1oPIoalGsJ"
server  |             DNS name: "EEfd0svnn8iw8E9qBPMqAOthoBw6dM4Sz5DdXjwdh6YGGIu5pOHvNJ
server  |                 jwCjDiwFhh5ngakuXSUaUutXa1Kv1ucLTibjQLHlNkFB82CrSfdepfqQylBrf
server  |                 Gzy6XmeuFPuEXIiQpGfumSsLrkf8PA6xB6xvIGQNUS5nhOYwNOifF8v98bzkD
server  |                 NRe0QhRMnv7CLYhpUeIaqy1x7IzCRXvBa9cccDU8AQNPpFwj0UMviLkLBjJpD
server  |                 xH85vY5UpEmXY"
server  |             DNS name: "QvNXhd87OVaMGvZo3MvXzH2m3dO6whrNXA2xKiOjGGNwRA55955UiN
server  |                 pIuLJ8zswHJImYESyKxyxitAQ1yZfHWaGCE96HvP7YObVf78BJOe5NdoDGcbj
server  |                 MNHeiMLl3IGHNmMFYhZEne9bAPrlRSy2yjSe7PbPV4Px8OP6psVRFguQDbymC
server  |                 qfeZod1aVecILL4y0HaxZz18anuXMAadqpO4URjpxYtyMqUxvVx5KwyBxIhPA
server  |                 pY1iSnin5hnnS"
server  |             DNS name: "uf5wjGgHO6wCvKBFsU4raLHkKOs6opIKv8Y7jQajkjcsDv5BweDit6
server  |                 jsoWNSve7ujPcmFl2aJeLlTIcyW9HGneSIqCLX1QX2Nqa77Mc0VRJMh7cypXX
server  |                 krPNcdjBE38er4LdXMwpLsnZj9X1O6l9nD5vee6ViQWGxMYD1BIHi0YXsGdAB
server  |                 dk9o1puU0u4xma78kjnvkDaYIENeqRsi0EtveeQ7c9ATov40cLNjR3QUFVGHo
server  |                 gcSuaFrS7sibV"
server  |             DNS name: "k7NZGogfQdQiWHgXZJtQJeVheYkN6lBgm7WKrZrvdzD86FMtgBhaMu
server  |                 X7yyHkxVb8jLLNE6tMk36qH47OIgL1jC0cxuyhBjoCs5L3P0KqoiMmUlYMxi1
server  |                 x4pQ3VHAF21kz1xHdsRW3mC5gw8WaJRyVrHfEmfxkfFJWb6zkbrAidC8YRTTk
server  |                 6Flt7BF8VSFgxKQ806cIo5pJVUbhyhjncvCYY2HIAyIj9oHduWzlVSUUh1vkt
server  |                 7hwQePX8sU496"
server  |             DNS name: "sljgWJf8T3WQ8n6BZmFT3vk8Mym49DnQ4nvEYwTDTAUAYewjjgsSfV
server  |                 vm2gBWtSr1ynEc1GAaISZMRPPW9Bq6q9BlsjUSjhiPmqjs7SclM0fJZl10hJV
server  |                 ysugkDK6kUledAbi9eRL0qsgU01KCbzDEdJE1kwnO49kmLDSpVG00BsrDdYfv
server  |                 yuLr3JqT8gvbopmRYH0dTjvvMyUTkBv46YueDMsLjawAjaY5P54mHEwBPAjf4
server  |                 joIP5uYAi5Atx"
server  |             DNS name: "gqoNYeD9UmPtTl6SVPcOKCfWjmzRoJwkZvXtEgmycBb5zZAegXiFCL
server  |                 vOybmdYHgQSwKzl78udd0LPqvoFkxq1iXuFq0ucYBAk9eKofv9MbDmcXRgmtP
server  |                 IwwE6qWChxtMA9IrRmiF8yfVM2Y4YKYCqcUwyUR9rMTyZghVD1MpUZ5HhseaW
server  |                 FgQo9KhMddW9aoiZbaWobKTFcqQ2MUPBnqu27N8TRiS4m1uFqeVlranH4HGnB
server  |                 uBJbhFv8j3lrc"
server  |             DNS name: "WBvXMZli8M2PiKRUAvrt9CTx0bc6BODNUK3hMumhs7nafOAaVkZcHk
server  |                 e51n3IIkxe21vNO82QCJCPEAHWRqvQOPhGdJaT3SPvk6fh0ZZmgXvuiyoaiNg
server  |                 5tY05GyBSOp6Ji3FWmprIPusDS1aw5Xx2ATcLWJ5mBv2fxwEb4gwYB501XgNM
server  |                 rcnFcnHYR0vlGW0PnTizBa5lNN0zn74SqlAmAxW9FWNv2coTiqdk65cyX23wq
server  |                 fRS1nR02FPlSH"
server  |             DNS name: "SSOBA9AZ6h2ok9zN3TWeow14WMfmhLg6o1xjWgEaP9tvCVyhCPZWFz
server  |                 5M7DFPrIpApiq7uRxAhVF30A5cjlAwhy5soiaYClF0rdLYpKmbsQc79znNy8W
server  |                 3KLW9TDrLfJXhGR3kyP6g2eNPGJcZqGliYOClE6W5x6adK9zxHBwICkroLuiO
server  |                 UOGSx7C6tsaZs68nlIL0M4NhM3plCH4PT1L6eaUbs1RzwaLFwc8PMSuE7vFiZ
server  |                 hDXUf3UNPRAE2"
server  | 
server  |             Name: Certificate Subject Key ID
server  |             Data:
server  |                 41:1a:36:b0:e7:f8:8a:2e:dc:25:06:3b:18:0f:08:a6:
server  |                 87:d1:df:9e
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 da:09:c2:37:82:72:37:6c:64:34:c5:b5:2b:eb:66:cc:
server  |                 05:36:84:bf
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:44:02:20:7a:6a:eb:e4:a0:95:93:99:34:6a:31:e7:
server  |         5e:27:48:56:5f:a1:a7:e0:e3:e2:fa:c3:4d:aa:fc:b0:
server  |         6d:f4:09:fd:02:20:2e:27:c8:ed:a4:37:c5:e1:dc:01:
server  |         01:23:83:47:08:d2:6b:0f:e4:e0:0a:b6:0f:c8:24:06:
server  |         fe:68:e9:9a:60:ce
server  |     Fingerprint (SHA-256):
server  |         6B:B6:15:F6:38:72:23:D6:F5:24:BA:AD:77:96:E4:31:3D:FD:CE:0C:11:B9:2C:6C:C1:80:4D:2C:D4:25:EA:EF
server  |     Fingerprint (SHA1):
server  |         E8:77:C5:D9:E8:97:89:D0:6E:C9:EC:1B:1F:6E:23:CA:AA:3D:01:D9
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.757 DEBUG [Server] Unsupported version: 57414954
server  | 0.757 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:35182 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.026291913s
server  | 0.758 DEBUG [Server] Unsupported version: 57414954
server  | 0.758 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:35182 IpTos(Cs0, NotEct) len 15
server  | 0.758 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.758 DEBUG [Server] Unsupported version: 57414954
server  | 0.758 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:35182 IpTos(Cs0, NotEct) len 15
server  | 0.758 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: tcpdump: listening on eth0listening on eth1, link-type EN10MB (Ethernet), link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | , snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | Starting QUIC client...
client  | Client params: 
client  | Test case: transfer
server  | 0.972 DEBUG [Server] Handle initial
server  | 0.972 INFO AddressValidation: no token; accepting
server  | 0.972 INFO [Server] Accept connection CID [13]: 6704156e4066c469415b555f45
server  | 0.972 DEBUG Overwrite initial version Version1 ==> Version1
server  | 0.976 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 0.976 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [13]: 6704156e4066c469415b555f45
server  | 0.976 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=6704156e4066c469415b555f45
server  | 0.976 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.976 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.976 DEBUG [Server 6704156e4066c469415b555f45] State change from Init -> WaitInitial
server  | 0.976 DEBUG [Server 6704156e4066c469415b555f45] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 1280
server  |   -> RX Padding { len: 968 }
server  |   -> RX Crypto { offset: 0, len: 267 }
server  | 0.976 DEBUG Read Ok(267) bytes
server  | 0.976 DEBUG 0-RTT: no token, no 0-RTT
server  | 0.977 DEBUG Read secret available for Handshake: SymKey [32]: f4021d095c17aaccb9b229dfdf8c381880efb894f1364f919a6d82cb1cfcaafb
server  | 0.977 DEBUG Write secret available for Handshake: SymKey [32]: 684d13e98924af37c34b685e503cdfeab1a748fc023e0cce83e33554e9dbdfff
server  | 0.977 DEBUG Writing transport parameters, msg=8
server  | 0.977 DEBUG Read secret available for ApplicationData: SymKey [32]: c27ab8e9af2e01ec0788a79d0b782a84a6614f936965abc9edb550e7bc5fb229
server  | 0.977 DEBUG Write secret available for ApplicationData: SymKey [32]: 95c6717c38f8f268b7321c89b8173fb0e2f58b1e851333d23cbd844f9e79286f
server  | 0.977 DEBUG [Agent 0x55e26d4b81d0] state -> InProgress
server  | 0.977 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.977 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.977 DEBUG [Crypto] Handshake keys installed
server  | 0.977 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 0.977 DEBUG [Crypto] Application write key installed
server  | 0.977 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 443, tv_nsec: 519817305 }
server  | 0.977 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:58072] Make permanent
server  | 0.977 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:58072] set as primary path
server  | 0.977 DEBUG [Server 6704156e4066c469415b555f45] State change from WaitInitial -> Handshaking
server  | 0.977 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.977 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.977 DEBUG CRYPTO for in offset=0, len=90
server  | 0.977 DEBUG [Server 6704156e4066c469415b555f45] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 136
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 0.977 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.977 DEBUG CRYPTO for hs offset=0, len=1056
server  | 0.977 DEBUG [Server 6704156e4066c469415b555f45] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 0, len: 1056 }
server  | 0.977 DEBUG packet_sent this=0x55e26d4b7b40, pn=0, ps=1096
server  | 0.977 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.977 DEBUG packet_sent this=0x55e26d4b7b40, pn=0, ps=136
server  | 0.977 DEBUG ECN probing: sent 1 probes
server  | 0.977 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.977 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.977 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.977 DEBUG CRYPTO for hs offset=1056, len=1191
server  | 0.977 DEBUG [Server 6704156e4066c469415b555f45] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1056, len: 1191 }
server  | 0.978 DEBUG packet_sent this=0x55e26d4b7b40, pn=1, ps=1232
server  | 0.978 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.978 DEBUG ECN probing: sent 2 probes
server  | 0.978 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.978 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.978 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.978 DEBUG CRYPTO for hs offset=2247, len=1191
server  | 0.978 DEBUG [Server 6704156e4066c469415b555f45] pn=2 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 2247, len: 1191 }
server  | 0.978 DEBUG packet_sent this=0x55e26d4b7b40, pn=2, ps=1232
server  | 0.978 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.978 DEBUG ECN probing: sent 3 probes
server  | 0.978 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.978 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.978 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.978 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.978 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.978 DEBUG [Server 6704156e4066c469415b555f45] delay duration 29.994304038s
server  | 0.978 DEBUG Setting timeout of 29.994304038s
server  | 0.978 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.978 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.978 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 0.978 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.978 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 0.978 DEBUG [Server 6704156e4066c469415b555f45] delay duration 29.994275544s
server  | 0.978 DEBUG Setting timeout of 29.994275544s
sim     | Dropping packet 2 (1280 bytes) from 193.167.0.100
sim     | Dropping packet 3 (45 bytes) from 193.167.0.100
sim     | Dropping packet 4 (45 bytes) from 193.167.0.100
sim     | Dropping packet 5 (45 bytes) from 193.167.0.100
sim     | Dropping packet 6 (39 bytes) from 193.167.0.100
server  | 1.402 DEBUG [Server 6704156e4066c469415b555f45] Drop packet number space in
server  | 1.402 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.402 INFO [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:58072] discarding a packet without an RTT estimate; guessing RTT=430.259637ms
server  | 1.402 DEBUG [Server 6704156e4066c469415b555f45] pn=5 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 39
server  |   -> RX Padding { len: 1 }
server  |   -> RX Ping
server  | 1.402 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 443, tv_nsec: 950076942 }
server  | 1.402 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 443, tv_nsec: 950076942 }
server  | 1.402 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 443, tv_nsec: 950076942 }
sim     | Dropping packet 7 (39 bytes) from 193.167.0.100
server  | 1.402 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.402 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.402 DEBUG CRYPTO for hs offset=0, len=1187
server  | 1.402 DEBUG [Server 6704156e4066c469415b555f45] pn=3 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 1187 }
server  | 1.402 DEBUG packet_sent this=0x55e26d4b7b40, pn=3, ps=1232
server  | 1.402 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.402 DEBUG ECN probing: sent 4 probes
server  | 1.402 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.402 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.402 DEBUG CRYPTO for hs offset=1187, len=1191
server  | 1.402 DEBUG [Server 6704156e4066c469415b555f45] pn=4 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1187, len: 1191 }
server  | 1.402 DEBUG packet_sent this=0x55e26d4b7b40, pn=4, ps=1232
server  | 1.402 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.402 DEBUG ECN probing: sent 5 probes
server  | 1.402 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.402 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.402 DEBUG CRYPTO for hs offset=2378, len=1191
server  | 1.402 DEBUG [Server 6704156e4066c469415b555f45] pn=5 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 2378, len: 1191 }
server  | 1.402 DEBUG packet_sent this=0x55e26d4b7b40, pn=5, ps=1232
server  | 1.402 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.402 DEBUG ECN probing: sent 6 probes
server  | 1.403 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.403 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.403 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.403 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.403 DEBUG [Server 6704156e4066c469415b555f45] delay duration 21.166274ms
server  | 1.403 DEBUG Setting timeout of 21.166274ms
server  | 1.403 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.403 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.403 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.403 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.403 DEBUG [Server 6704156e4066c469415b555f45] delay duration 21.137881ms
server  | 1.403 DEBUG Setting timeout of 21.137881ms
server  | 1.425 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.425 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.425 DEBUG CRYPTO for hs offset=3569, len=1191
server  | 1.425 DEBUG [Server 6704156e4066c469415b555f45] pn=6 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 3569, len: 1191 }
server  | 1.425 DEBUG packet_sent this=0x55e26d4b7b40, pn=6, ps=1232
server  | 1.425 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.425 DEBUG ECN probing: sent 7 probes
server  | 1.425 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.425 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.425 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.425 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.425 DEBUG [Server 6704156e4066c469415b555f45] delay duration 20.411458ms
server  | 1.425 DEBUG Setting timeout of 20.411458ms
server  | 1.425 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.425 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.425 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.425 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.425 DEBUG [Server 6704156e4066c469415b555f45] delay duration 20.330796ms
server  | 1.425 DEBUG Setting timeout of 20.330796ms
server  | 1.434 DEBUG [Server 6704156e4066c469415b555f45] pn=6 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  | 1.434 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=3]
server  | 1.434 DEBUG [LossRecovery] ACK for Handshake - largest_acked=3
server  | 1.434 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3696/17112 ssthresh 18446744073709551615] slow start += 4792
server  | 1.434 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3696/17112 ssthresh 18446744073709551615] on_packets_acked this=0x55e26d4b7b40, limited=0, bytes_in_flight=3696, cwnd=17112, state=SlowStart, new_acked=4792
server  | 1.434 DEBUG Acked crypto frame space=hs offset=0 length=1187
server  | 1.434 DEBUG Acked crypto frame space=hs offset=2247 length=1191
server  | 1.434 DEBUG Acked crypto frame space=hs offset=1056 length=1191
server  | 1.434 DEBUG Acked crypto frame space=hs offset=0 length=1056
server  | 1.434 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 443, tv_nsec: 981854478 }
server  | 1.434 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.434 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.434 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.434 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.434 DEBUG [Server 6704156e4066c469415b555f45] delay duration 3.832534ms
server  | 1.434 DEBUG Setting timeout of 3.832534ms
server  | 1.434 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.434 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.434 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.434 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.434 DEBUG [Server 6704156e4066c469415b555f45] delay duration 3.642146ms
server  | 1.434 DEBUG Setting timeout of 3.642146ms
server  | 1.435 DEBUG [Server 6704156e4066c469415b555f45] pn=7 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) }
server  | 1.435 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=4]
server  | 1.435 DEBUG [LossRecovery] ACK for Handshake - largest_acked=4
server  | 1.435 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2464/18344 ssthresh 18446744073709551615] slow start += 1232
server  | 1.435 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2464/18344 ssthresh 18446744073709551615] on_packets_acked this=0x55e26d4b7b40, limited=0, bytes_in_flight=2464, cwnd=18344, state=SlowStart, new_acked=1232
server  | 1.435 DEBUG Acked crypto frame space=hs offset=1187 length=1191
server  | 1.435 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 443, tv_nsec: 982789667 }
server  | 1.435 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.435 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.435 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.435 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.435 DEBUG [Server 6704156e4066c469415b555f45] delay duration 629.095µs
server  | 1.435 DEBUG Setting timeout of 629.095µs
server  | 1.435 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.435 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.435 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.435 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.435 DEBUG [Server 6704156e4066c469415b555f45] delay duration 556.88µs
server  | 1.435 DEBUG Setting timeout of 556.88µs
server  | 1.436 DEBUG [Server 6704156e4066c469415b555f45] pn=8 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) }
server  | 1.436 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=5]
server  | 1.436 DEBUG [LossRecovery] ACK for Handshake - largest_acked=5
server  | 1.436 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1232/19576 ssthresh 18446744073709551615] slow start += 1232
server  | 1.436 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1232/19576 ssthresh 18446744073709551615] on_packets_acked this=0x55e26d4b7b40, limited=0, bytes_in_flight=1232, cwnd=19576, state=SlowStart, new_acked=1232
server  | 1.436 DEBUG Acked crypto frame space=hs offset=2378 length=1191
server  | 1.436 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 443, tv_nsec: 983765963 }
server  | 1.436 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.436 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.436 DEBUG CRYPTO for hs offset=4760, len=1191
server  | 1.436 DEBUG [Server 6704156e4066c469415b555f45] pn=7 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 4760, len: 1191 }
server  | 1.436 DEBUG packet_sent this=0x55e26d4b7b40, pn=7, ps=1232
server  | 1.436 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.436 DEBUG ECN probing: sent 8 probes
server  | 1.436 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.436 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.436 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.436 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.436 DEBUG [Server 6704156e4066c469415b555f45] delay duration 7.111569ms
server  | 1.436 DEBUG Setting timeout of 7.111569ms
server  | 1.436 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.436 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.436 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.436 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.436 DEBUG [Server 6704156e4066c469415b555f45] delay duration 7.082825ms
server  | 1.436 DEBUG Setting timeout of 7.082825ms
server  | 1.444 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.444 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.444 DEBUG CRYPTO for hs offset=5951, len=1191
server  | 1.444 DEBUG [Server 6704156e4066c469415b555f45] pn=8 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 5951, len: 1191 }
server  | 1.444 DEBUG packet_sent this=0x55e26d4b7b40, pn=8, ps=1232
server  | 1.444 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.444 DEBUG ECN probing: sent 9 probes
server  | 1.444 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.444 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.444 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.444 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.444 DEBUG [Server 6704156e4066c469415b555f45] delay duration 8.319022ms
server  | 1.444 DEBUG Setting timeout of 8.319022ms
server  | 1.444 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.444 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.444 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.444 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.444 DEBUG [Server 6704156e4066c469415b555f45] delay duration 8.289667ms
server  | 1.444 DEBUG Setting timeout of 8.289667ms
server  | 1.454 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.454 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.454 DEBUG CRYPTO for hs offset=7142, len=1191
server  | 1.454 DEBUG [Server 6704156e4066c469415b555f45] pn=9 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 7142, len: 1191 }
server  | 1.454 DEBUG packet_sent this=0x55e26d4b7b40, pn=9, ps=1232
server  | 1.454 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.454 DEBUG ECN probing: sent 10 probes
server  | 1.454 DEBUG ECN probing concluded with 10 probes sent
server  | 1.454 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.454 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.454 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.454 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.454 DEBUG [Server 6704156e4066c469415b555f45] delay duration 7.518969ms
server  | 1.454 DEBUG Setting timeout of 7.518969ms
server  | 1.454 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.454 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.454 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.454 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.454 DEBUG [Server 6704156e4066c469415b555f45] delay duration 7.489985ms
server  | 1.454 DEBUG Setting timeout of 7.489985ms
server  | 1.456 DEBUG [Server 6704156e4066c469415b555f45] pn=9 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 1.456 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=6]
server  | 1.456 DEBUG [LossRecovery] ACK for Handshake - largest_acked=6
server  | 1.456 INFO ECN validation succeeded, path is capable
server  | 1.456 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3696/20808 ssthresh 18446744073709551615] slow start += 1232
server  | 1.456 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3696/20808 ssthresh 18446744073709551615] on_packets_acked this=0x55e26d4b7b40, limited=0, bytes_in_flight=3696, cwnd=20808, state=SlowStart, new_acked=1232
server  | 1.456 DEBUG Acked crypto frame space=hs offset=3569 length=1191
server  | 1.456 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 444, tv_nsec: 4199692 }
server  | 1.456 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.456 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.456 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.456 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.456 DEBUG [Server 6704156e4066c469415b555f45] delay duration 4.392967ms
server  | 1.456 DEBUG Setting timeout of 4.392967ms
server  | 1.456 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.456 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.456 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.456 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.456 DEBUG [Server 6704156e4066c469415b555f45] delay duration 4.337252ms
server  | 1.456 DEBUG Setting timeout of 4.337252ms
server  | 1.461 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.461 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.461 DEBUG CRYPTO for hs offset=8333, len=1191
server  | 1.461 DEBUG [Server 6704156e4066c469415b555f45] pn=10 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 8333, len: 1191 }
server  | 1.461 DEBUG packet_sent this=0x55e26d4b7b40, pn=10, ps=1232
server  | 1.461 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.461 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.461 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.461 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.461 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.461 DEBUG [Server 6704156e4066c469415b555f45] delay duration 7.056581ms
server  | 1.462 DEBUG Setting timeout of 7.056581ms
server  | 1.462 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.462 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.462 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.462 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.462 DEBUG [Server 6704156e4066c469415b555f45] delay duration 7.033828ms
server  | 1.462 DEBUG Setting timeout of 7.033828ms
server  | 1.467 DEBUG [Server 6704156e4066c469415b555f45] pn=10 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
server  | 1.467 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=7]
server  | 1.467 DEBUG [LossRecovery] ACK for Handshake - largest_acked=7
server  | 1.467 DEBUG on_packets_acked this=0x55e26d4b7b40, limited=1, bytes_in_flight=3696, cwnd=20808, state=SlowStart, new_acked=1232
server  | 1.467 DEBUG Acked crypto frame space=hs offset=4760 length=1191
server  | 1.467 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 444, tv_nsec: 15366814 }
server  | 1.467 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.467 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.467 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.467 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.467 DEBUG [Server 6704156e4066c469415b555f45] delay duration 305.641µs
server  | 1.468 DEBUG Setting timeout of 305.641µs
server  | 1.468 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.468 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.468 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.468 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.468 DEBUG [Server 6704156e4066c469415b555f45] delay duration 246.61µs
server  | 1.468 DEBUG Setting timeout of 246.61µs
server  | 1.469 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.469 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.469 DEBUG CRYPTO for hs offset=9524, len=450
server  | 1.469 DEBUG [Server 6704156e4066c469415b555f45] pn=11 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 491
server  |   TX -> Crypto { offset: 9524, len: 450 }
server  | 1.469 DEBUG packet_sent this=0x55e26d4b7b40, pn=11, ps=491
server  | 1.469 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.469 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.469 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.469 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.469 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.469 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.885589ms
server  | 1.469 DEBUG Setting timeout of 1.885589ms
server  | 1.469 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.469 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.469 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.469 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.469 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.861334ms
server  | 1.469 DEBUG Setting timeout of 1.861334ms
server  | 1.472 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.472 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.472 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.472 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.472 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.315550316s
server  | 1.472 DEBUG Setting timeout of 1.315550316s
server  | 1.472 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.472 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.472 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.472 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.472 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.315527994s
server  | 1.472 DEBUG Setting timeout of 1.315527994s
server  | 1.476 DEBUG [Server 6704156e4066c469415b555f45] pn=11 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) }
server  | 1.476 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=8]
server  | 1.476 DEBUG [LossRecovery] ACK for Handshake - largest_acked=8
server  | 1.476 DEBUG on_packets_acked this=0x55e26d4b7b40, limited=1, bytes_in_flight=2955, cwnd=20808, state=SlowStart, new_acked=1232
server  | 1.476 DEBUG Acked crypto frame space=hs offset=5951 length=1191
server  | 1.476 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 444, tv_nsec: 23592955 }
server  | 1.476 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.476 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.476 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.476 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.476 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.220217466s
server  | 1.476 DEBUG Setting timeout of 1.220217466s
server  | 1.476 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.476 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.476 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.476 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.476 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.220152634s
server  | 1.476 DEBUG Setting timeout of 1.220152634s
server  | 1.486 DEBUG [Server 6704156e4066c469415b555f45] pn=12 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) }
server  | 1.486 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=9]
server  | 1.486 DEBUG [LossRecovery] ACK for Handshake - largest_acked=9
server  | 1.486 DEBUG on_packets_acked this=0x55e26d4b7b40, limited=1, bytes_in_flight=1723, cwnd=20808, state=SlowStart, new_acked=1232
server  | 1.486 DEBUG Acked crypto frame space=hs offset=7142 length=1191
server  | 1.486 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 444, tv_nsec: 33754055 }
server  | 1.486 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.486 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.486 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.486 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.486 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.112735524s
server  | 1.486 DEBUG Setting timeout of 1.112735524s
server  | 1.486 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.486 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.486 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.486 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.486 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.112646196s
server  | 1.486 DEBUG Setting timeout of 1.112646196s
server  | 1.493 DEBUG [Server 6704156e4066c469415b555f45] pn=13 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 11, Ce: 0})) }
server  | 1.493 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=10]
server  | 1.493 DEBUG [LossRecovery] ACK for Handshake - largest_acked=10
server  | 1.493 DEBUG on_packets_acked this=0x55e26d4b7b40, limited=1, bytes_in_flight=491, cwnd=20808, state=SlowStart, new_acked=1232
server  | 1.493 DEBUG Acked crypto frame space=hs offset=8333 length=1191
server  | 1.493 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 444, tv_nsec: 40858376 }
server  | 1.493 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.493 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.493 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.493 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.493 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.007437367s
server  | 1.493 DEBUG Setting timeout of 1.007437367s
server  | 1.493 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.493 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.493 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.493 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.493 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.007376092s
server  | 1.493 DEBUG Setting timeout of 1.007376092s
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] pn=14 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, NotEct) len 84
server  |   -> RX Ack { largest_acknowledged: 11, ack_delay: 0, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=hs, ranges=[0..=11]
server  | 1.500 DEBUG [LossRecovery] ACK for Handshake - largest_acked=11
server  | 1.500 DEBUG on_packets_acked this=0x55e26d4b7b40, limited=1, bytes_in_flight=0, cwnd=20808, state=SlowStart, new_acked=491
server  | 1.500 DEBUG Acked crypto frame space=hs offset=9524 length=450
server  | 1.500 DEBUG Read Ok(36) bytes
server  | 1.500 DEBUG [Agent 0x55e26d4b81d0] 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  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] TLS connection complete
server  | 1.500 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:58072] Path validated Instant { tv_sec: 444, tv_nsec: 47895130 }
server  | 1.500 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.500 DEBUG [Crypto] application read keys installed
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] State change from Handshaking -> Connected
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] State change from Connected -> Confirmed
server  | 1.500 DEBUG PMTUD started with probe size 1380
server  | 1.500 INFO [Server 6704156e4066c469415b555f45] Connection established
server  | 1.500 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 47895130 }
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.500 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 001a0f9cbedfa3bd2486)
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] pn=12 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 41
server  |   TX -> Ack { largest_acknowledged: 14, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: None }
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] Drop packet number space hs
server  | 1.500 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.500 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [185, 199, 186, 87, 68, 26, 95, 209, 44, 155], stateless_reset_token: [14, 21, 76, 39, 200, 177, 92, 14, 130, 161, 73, 125, 50, 180, 150, 62] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [142, 242, 50, 22, 109, 19, 4, 31, 92, 187], stateless_reset_token: [70, 251, 15, 165, 220, 88, 61, 184, 202, 193, 105, 198, 136, 149, 173, 35] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [109, 108, 147, 7, 5, 168, 11, 163, 191, 238], stateless_reset_token: [172, 121, 252, 197, 249, 50, 6, 90, 189, 237, 46, 23, 129, 84, 153, 37] }
server  |   TX -> Padding { len: 1182 }
server  | 1.500 DEBUG packet_sent this=0x55e26d4b7b40, pn=0, ps=1291
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.500 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.500 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.500 DEBUG packet_sent this=0x55e26d4b7b40, pn=1, ps=1332
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.500 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.500 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] delay duration 4.423276ms
server  | 1.500 DEBUG Setting timeout of 4.423276ms
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 48
server  |   -> RX Stream { stream_id: 0, offset: 0, len: >>17, fin: true }
server  | 1.500 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 68376936 }
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.500 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.500 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.500 DEBUG [Server 6704156e4066c469415b555f45] delay duration 4.401595ms
server  | 1.500 DEBUG Setting timeout of 4.401595ms
server  | 1.501 DEBUG [Server 6704156e4066c469415b555f45] send session ticket 4068000d6704156e4066c469415b555f450104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a001a0f9cbedfa3bd2486110c000000017aea9a9a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.501 DEBUG Path = 'ynooxovzxw'
server  | 1.501 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.501 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.501 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.501 DEBUG [Server 6704156e4066c469415b555f45] delay duration 3.400332ms
server  | 1.501 DEBUG Setting timeout of 3.400332ms
server  | 1.506 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.506 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.506 DEBUG [Server 6704156e4066c469415b555f45] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1212, fin: false }
server  | 1.506 DEBUG packet_sent this=0x55e26d4b7b40, pn=2, ps=1232
server  | 1.506 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.506 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.506 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.506 DEBUG [Server 6704156e4066c469415b555f45] delay duration 3.708561ms
server  | 1.506 DEBUG Setting timeout of 3.708561ms
server  | 1.506 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.506 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.506 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.506 DEBUG [Server 6704156e4066c469415b555f45] delay duration 3.685859ms
server  | 1.506 DEBUG Setting timeout of 3.685859ms
server  | 1.511 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.511 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.511 DEBUG [Server 6704156e4066c469415b555f45] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1212, len: >>1210, fin: false }
server  | 1.511 DEBUG packet_sent this=0x55e26d4b7b40, pn=3, ps=1232
server  | 1.511 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.511 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.511 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.511 DEBUG [Server 6704156e4066c469415b555f45] delay duration 3.000358ms
server  | 1.511 DEBUG Setting timeout of 3.000358ms
server  | 1.511 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.511 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.511 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.511 DEBUG [Server 6704156e4066c469415b555f45] delay duration 2.976964ms
server  | 1.511 DEBUG Setting timeout of 2.976964ms
server  | 1.515 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.515 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.515 DEBUG [Server 6704156e4066c469415b555f45] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2422, len: >>1210, fin: false }
server  | 1.515 DEBUG packet_sent this=0x55e26d4b7b40, pn=4, ps=1232
server  | 1.515 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.515 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.515 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.515 DEBUG [Server 6704156e4066c469415b555f45] delay duration 3.294732ms
server  | 1.515 DEBUG Setting timeout of 3.294732ms
server  | 1.515 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.515 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.515 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.515 DEBUG [Server 6704156e4066c469415b555f45] delay duration 3.269014ms
server  | 1.515 DEBUG Setting timeout of 3.269014ms
server  | 1.519 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.519 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.519 DEBUG [Server 6704156e4066c469415b555f45] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3632, len: >>1210, fin: false }
server  | 1.519 DEBUG packet_sent this=0x55e26d4b7b40, pn=5, ps=1232
server  | 1.519 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.519 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.519 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.519 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.253196ms
server  | 1.519 DEBUG Setting timeout of 1.253196ms
server  | 1.519 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.519 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.519 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.519 DEBUG [Server 6704156e4066c469415b555f45] delay duration 1.236465ms
server  | 1.519 DEBUG Setting timeout of 1.236465ms
server  | 1.522 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.522 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.522 DEBUG [Server 6704156e4066c469415b555f45] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 27
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 2730, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.522 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.522 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.522 DEBUG CRYPTO for ap offset=0, len=321
server  | 1.522 DEBUG [Server 6704156e4066c469415b555f45] pn=7 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 672
server  |   TX -> Stream { stream_id: 0, offset: 4842, len: 278, fin: true }
server  |   TX -> Crypto { offset: 0, len: 321 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 76, 65, 140, 79, 77, 184, 194, 236, 99, 191, 110, 191, 136, 115, 230, 101, 42, 77, 176, 93, 69, 22, 100, 29, 85, 102, 183, 231, 126, 246, 200, 247, 90, 2, 217, 239] }
server  | 1.522 DEBUG packet_sent this=0x55e26d4b7b40, pn=7, ps=672
server  | 1.522 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.522 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.522 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.522 DEBUG [Server 6704156e4066c469415b555f45] delay duration 2.932223ms
server  | 1.522 DEBUG Setting timeout of 2.932223ms
server  | 1.522 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.522 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.522 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.522 DEBUG [Server 6704156e4066c469415b555f45] delay duration 2.910522ms
server  | 1.522 DEBUG Setting timeout of 2.910522ms
server  | 1.527 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.527 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.527 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.527 DEBUG [Server 6704156e4066c469415b555f45] delay duration 958.019203ms
server  | 1.527 DEBUG Setting timeout of 958.019203ms
server  | 1.527 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.527 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.527 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.527 DEBUG [Server 6704156e4066c469415b555f45] delay duration 957.999656ms
server  | 1.527 DEBUG Setting timeout of 957.999656ms
server  | 1.532 DEBUG [Server 6704156e4066c469415b555f45] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 39
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 18, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   -> RX RetireConnectionId { sequence_number: 0 }
server  | 1.532 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=ap, ranges=[0..=0]
server  | 1.532 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0
server  | 1.532 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6932/22099 ssthresh 18446744073709551615] slow start += 1291
server  | 1.532 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6932/22099 ssthresh 18446744073709551615] on_packets_acked this=0x55e26d4b7b40, limited=0, bytes_in_flight=6932, cwnd=22099, state=SlowStart, new_acked=1291
server  | 1.532 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 444, tv_nsec: 99995358 }
server  | 1.532 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.532 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.532 DEBUG [Server 6704156e4066c469415b555f45] pn=8 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 48
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [86, 71, 239, 72, 239, 67, 231, 42, 15, 255], stateless_reset_token: [155, 77, 158, 116, 20, 202, 119, 7, 69, 160, 69, 230, 100, 61, 94, 131] }
server  | 1.532 DEBUG packet_sent this=0x55e26d4b7b40, pn=8, ps=48
server  | 1.532 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.532 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.532 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.532 DEBUG [Server 6704156e4066c469415b555f45] delay duration 19.872931ms
server  | 1.532 DEBUG Setting timeout of 19.872931ms
server  | 1.532 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.532 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.532 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.532 DEBUG [Server 6704156e4066c469415b555f45] delay duration 19.846642ms
server  | 1.532 DEBUG Setting timeout of 19.846642ms
server  | 1.537 DEBUG [Server 6704156e4066c469415b555f45] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 37
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 8, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
server  | 1.537 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=ap, ranges=[0..=2]
server  | 1.537 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 1.537 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4416/24663 ssthresh 18446744073709551615] slow start += 2564
server  | 1.537 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4416/24663 ssthresh 18446744073709551615] on_packets_acked this=0x55e26d4b7b40, limited=0, bytes_in_flight=4416, cwnd=24663, state=SlowStart, new_acked=2564
server  | 1.537 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.537 DEBUG PMTUD started with probe size 1420
server  | 1.537 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.537 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.537 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.537 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.537 DEBUG [Server 6704156e4066c469415b555f45] pn=9 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1353 }
server  | 1.537 DEBUG packet_sent this=0x55e26d4b7b40, pn=9, ps=1372
server  | 1.537 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.537 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.537 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.537 DEBUG [Server 6704156e4066c469415b555f45] delay duration 14.846716ms
server  | 1.537 DEBUG Setting timeout of 14.846716ms
server  | 1.537 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.537 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.537 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.537 DEBUG [Server 6704156e4066c469415b555f45] delay duration 14.819985ms
server  | 1.537 DEBUG Setting timeout of 14.819985ms
server  | 1.547 DEBUG [Server 6704156e4066c469415b555f45] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 37
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 7, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) }
server  | 1.547 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=ap, ranges=[0..=4]
server  | 1.547 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
server  | 1.547 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3324/27127 ssthresh 18446744073709551615] slow start += 2464
server  | 1.547 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3324/27127 ssthresh 18446744073709551615] on_packets_acked this=0x55e26d4b7b40, limited=0, bytes_in_flight=3324, cwnd=27127, state=SlowStart, new_acked=2464
server  | 1.547 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.547 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.547 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.547 DEBUG [Server 6704156e4066c469415b555f45] delay duration 5.513806ms
server  | 1.547 DEBUG Setting timeout of 5.513806ms
server  | 1.547 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.547 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.547 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.547 DEBUG [Server 6704156e4066c469415b555f45] delay duration 5.444205ms
server  | 1.547 DEBUG Setting timeout of 5.444205ms
server  | 1.553 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.553 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.553 DEBUG [Server 6704156e4066c469415b555f45] pn=10 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 27
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 770, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  | 1.553 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.553 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.553 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.553 DEBUG [Server 6704156e4066c469415b555f45] delay duration 691.34432ms
server  | 1.553 DEBUG Setting timeout of 691.34432ms
server  | 1.553 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.553 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.553 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.553 DEBUG [Server 6704156e4066c469415b555f45] delay duration 691.320705ms
server  | 1.553 DEBUG Setting timeout of 691.320705ms
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 37
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 7, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] Rx ACK space=ap, ranges=[0..=7]
server  | 1.554 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7
server  | 1.554 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1420/29031 ssthresh 18446744073709551615] slow start += 1904
server  | 1.554 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1420/29031 ssthresh 18446744073709551615] on_packets_acked this=0x55e26d4b7b40, limited=0, bytes_in_flight=1420, cwnd=29031, state=SlowStart, new_acked=1904
server  | 1.554 DEBUG Acked crypto frame space=ap offset=0 length=321
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.554 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] pn=11 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 20
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 1.554 DEBUG packet_sent this=0x55e26d4b7b40, pn=11, ps=21
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.554 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.554 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] delay duration 635.37967ms
server  | 1.554 DEBUG Setting timeout of 635.37967ms
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 32
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" }
server  | 1.554 INFO [Server 6704156e4066c469415b555f45] ConnectionClose received. Error code: Application(0) frame type 0 reason 
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 446, tv_nsec: 8093565 } }
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.554 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.554 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 444, tv_nsec: 101573921 }
server  | 1.554 DEBUG [Server 6704156e4066c469415b555f45] pn=12 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:58072 IpTos(Cs0, Ect0) len 30
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  | 1.554 DEBUG Setting timeout of 1.906444553s
server  | 1.554 DEBUG Setting timeout of 1.906430006s

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:39:57,187 Using the client's key log file.
2025-04-30 09:39:57,199 
2025-04-30 09:39:57,200 Using the client's key log file.
2025-04-30 09:39:57,200 Using the client's key log file.
2025-04-30 09:39:57,210 
2025-04-30 09:39:57,211 Using the client's key log file.
2025-04-30 09:39:57,211 Using the client's key log file.
2025-04-30 09:39:57,871 Check of downloaded files succeeded.
2025-04-30 09:39:58,208 Server sent 9974 bytes in Handshake CRYPTO frames.
2025-04-30 09:39:58,584 Received a 1280 byte Initial packet from the client. Amplification limit: 3840
2025-04-30 09:39:58,584 Received a 1232 byte Handshake packet from the server. Total: 1232
2025-04-30 09:39:58,584 Received a 1232 byte Handshake packet from the server. Total: 2464
2025-04-30 09:39:58,584 Received a 1232 byte Handshake packet from the server. Total: 3696