2025-04-30 12:26:52,390 Generated random file: srjxfeupbr of size: 5120
2025-04-30 12:26:52,390 Requests: https://server4:443/srjxfeupbr
2025-04-30 12:26:52,766 
2025-04-30 12:26:52,766 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_y5_8blv_/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_danherzy/ DOWNLOADS=/tmp/download_9190y6p2/ SERVER_LOGS=/tmp/logs_server_q4i07sjc CLIENT_LOGS=/tmp/logs_client_dphbjwd2 SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=aiortc/aioquic-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/srjxfeupbr"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:27:07,710  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...
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksumming: off
client  | 	tx-checksum-ip-generic: off
client  | 	tx-checksum-sctp: off
client  | tcp-segmentation-offload: off
client  | 	tx-tcp-segmentation: off [requested on]
client  | 	tx-tcp-ecn-segmentation: off [requested on]
client  | 	tx-tcp-mangleid-segmentation: off [requested on]
client  | 	tx-tcp6-segmentation: off [requested on]
client  | tx-udp-segmentation: off [requested on]
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
server  | Endpoint's IPv4 address is 193.167.100.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
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.FBP6baJJr5
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.FBP6baJJr5
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.FBP6baJJr5 -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  |             0d:79:84:81:2a:2d:a5:cf:3a:7f:c6:cb:a4:1f:b6:58:
server  |             19:34:fa:d0
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner intermediate 8"
server  |         Validity:
server  |             Not Before: Wed Apr 30 12:26:52 2025
server  |             Not After : Sat May 10 12:26:52 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:08:17:d8:d0:6c:b0:f0:f2:47:ae:6c:da:ef:37:6a:
server  |                     1d:54:fc:9f:6b:1c:fb:26:f0:0a:2f:26:02:52:ee:c1:
server  |                     1f:81:75:a7:71:29:88:88:39:fd:10:2b:79:db:bb:e7:
server  |                     f1:63:b5:02:67:7c:20:15:6c:19:14:07:0a:43:70:7f:
server  |                     37
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: "Ge5M3Uh3RDCsLVdELURD5ErJ5APfokv2bJdK3sHCeazkIr51Bbh4Hx
server  |                 3PXZp9bmGxWPgXYiYL12dM3O8wIv0EamDzM6jea2d0P0gIqh22UAN8atjkrt9
server  |                 qb24mHejIynthUftNZfPnfu6vB2sPuY8Fn4rVazCVACn4XorxqMIoGO1BeR4u
server  |                 qxZWnQPk9fbRLUJE7luZ1MmScOePQV9OgqLK8u98SddjjYrgxkP35EDYqlnNv
server  |                 Rb6RKqgTt0XXB"
server  |             DNS name: "1rRh27vyIVlWthepE6fp5rI3qwiH3zIjJH07h987nr9iG5OlnpZiRi
server  |                 GnXiwX7IhXJmp8cn0cvSmRgLbKkfCgURowtw2Li9C3OLnBF6BNWgj51LfCTvh
server  |                 62YoZQm5ZFlVDCWizTDAynJlRHti9yq1zCvhvZP7gOmgTKu6o5h71Zeb72UEC
server  |                 9QgD7tk6V2g61ohiE7hYgzi3a2WJHb5t8wrtwYLHE15CQVkdygYzAGnAwhhlW
server  |                 l1Io0lYf7QaYg"
server  |             DNS name: "9r5L9v83zLE6Q0hrsDHJUE5sdXQF46YCuPKtSbfQnMoKQuz8Kc91zh
server  |                 FP7WJbWB4ZET4sClHtTTmCqFQRltpUQRZM686ENOPf10XVQWxhNuP18R02cl8
server  |                 oE9sraQCNnDvfVjNFFCeqKuu9SdQcTnSPHqqWyoi9JJjhSfcE29M3kue6FE9K
server  |                 EYJYObfYOeq2Cnvu8ajd73ij4lh142pOAqjXaOYMXYhKtbqIYpVNMGTpnGkMS
server  |                 VTbFdn4EmMBDN"
server  |             DNS name: "tCuIzePzxsCz7I0spVcsgy5Z8RLaJGITxAxXPssZlHHmgJsUrRGNmD
server  |                 ULVQEgJgPIz1t6BTD2uD6jCJNw6uVrGhuIL6Eac0fR8DU4LbZlaVBiT0SHCsy
server  |                 rI0HPc0nfnkl5uUAEltMVUtFRw9qYso5XS6n5E3FVqQmPU7g9rhq2Pi4ooDaJ
server  |                 gWRcxRxtriDzKFDG3dswvZudBCad6872YMESHk1ocunyl0fismoz4nxBR7OBX
server  |                 2DopjIFgyOai7"
server  |             DNS name: "4j2BE4cNJVUBn62C50H5LtrxfUQNSc0T2l4vaj8DTWFnXwLnlpTjlk
server  |                 DB5n1lN0HwMO6vMI3thJIvfdxYqOQqK81or1rMwXqAnwRhVhy6FpbGAyEsUOL
server  |                 qo1SmLOIHiuxA1tL8qXn9SodJeC6jhOOtwWVLEIOxoPJ1V5NlWE3IHL15T5Qt
server  |                 38nX9OtD5kIZaSwpZ57cMOp2AG2pKBB4OyZDxnRYhPfdhYIN3UMSbfIWv0a2G
server  |                 AHzKi6gezEdAc"
server  |             DNS name: "QzVys4Qe00VmCZD5YrN3vAgWaHRH5KKJiwkI1otdWzey5PDU6S0CTP
server  |                 KYbrhPMFNXnO2ymfvf7oc8g87tXTgjEwd4HjjMidxg6ePtc5YWSg63ulIav29
server  |                 orNOujIxPYnyArQ5SRIZZ6ex16lMzg6pX8S2BhW7rc4LXo1GGGWKAmEACiM6S
server  |                 VK9TrMRwDptYbMuGaH1w173WSmFbU1UhWAlw6UD5EKoj2pzxM0wCeVImrg2OS
server  |                 39lrhVwaEJRiL"
server  |             DNS name: "9z0OGvZ5W0vlEEOA7VWA4MAtNKbjThBesT98aHMOxZfSNTlg4xmE2M
server  |                 nFIxhGEyAPSy6utloMl0uGExCOJsazrkIhtuEzdWp54BiZaStmz9hLhQohKTf
server  |                 0froQ7BEmv7qnIlSzWkpUXmbH0Artg9EgKPbhBxV86K5k8HN0DTUmvMYSfPwT
server  |                 kyxQydnh6sXHOBAjKOYZDXUwct8LV7VzG8hl3ugJvhbjwH7AXgNEIBWGnVZdN
server  |                 BxdJwWe8SIIDt"
server  |             DNS name: "V08WxkJpIlq1qwoy5iyXVKce4q64NJuivdFNb3GlSD9XLgJB7zj0U0
server  |                 SybIrltuMKR3hvlFSbanQWP69i81q8FYR8wNnxlzXUaPnmLyJ5pXyNkKvb877
server  |                 YwKq0yK7nNZWIVud8zOJ10QKV2Zhj1ScJpb1mqZVhrPkEk4aPmbd34nmMdboe
server  |                 8r03huf18oEfU2n12d0WNReNgihZjqoVpcExSxDjdya7pZv8NUko944hAhIxa
server  |                 pHbGGBSwKIWTx"
server  |             DNS name: "n8QL7Fm79hyf5wnxrWRJRb7zBqteCRPYIZXAmjf2T4DDtIgbYvJJTo
server  |                 yBYwH4q27NqVsVTkv3Hc5H99JHCkrdCpr2HCHpZrAapaMZwQFPhpdlcMumcXx
server  |                 OrZYenjhUDYp20hqgGJS2HW5eeqoSwCRs1HpEnwZeaUhCd914AjqCMk3quZPT
server  |                 ApLte5G2H2edVVpxWEV3KXKP5HPxgHlOWmFeOZsim66uaT3fieNE93WnJ34XF
server  |                 in6UERAlfc8iB"
server  |             DNS name: "WC4PqM8v8WUpl7Y81ptBBlkLHWtpTd6myC0rjO6brBJSTUzOyl1Xwv
server  |                 fv3b0p7mDZsn6rlLgynrs32rxexwBN5JMjtX6AuLUrLAS3mWrsZKceROztmBl
server  |                 cWXorceuQLOGeWhaSlXpR9lq57nRm2Zpa0cYIjOpRQZLm5ZxCS2kI6eSiLHQR
server  |                 9bjJuFf38Rqqo52Ns8zk8l88jKZFAbs8uPydZpRM7bfK04rrp2TYBT2cEGyd0
server  |                 G55hipMMLRmOF"
server  |             DNS name: "sAehw1GU6eOOuYt3L3efENadjsT1APyXvoDpCNUFNOnv0Ng7nPEpgU
server  |                 AYm8OQ1FpKWZ9Q0CDbJ4Stn1a31ArAiytGi2KVEspqvfGdfRBQtqiiWSONd6m
server  |                 tPa8Wal91jPiLDDQ40vQrAodZnX2ZFRsKUsZ5JLD8PbeXNOwD4TGE0B8u8BX7
server  |                 GFW7LZEJiD9ONd2BAf1rgArcIfbX66Kl6nizHog6vuOQ9mddxQOtLQZ7PuyFh
server  |                 fZmGlsa2dHOT2"
server  |             DNS name: "rou6RRpK1O85JLCx5bNagggPIt6F9ZdfxDx6aOJsVogsgqtVo4p4Ej
server  |                 PIvz3AJEBG1ImyzFi4YUsfLWA5GIf6pEGGeSpjXssRsy9C6rNdhJhpx5A3P6I
server  |                 kV92o02HzN09byrqnRwy4MyIb71Zck2BonOfw4mFsNM00sTabvAphJsPUqkC6
server  |                 XvjjOGzUctxl1VOrwriAnTTAmM2jZJ9ejisDqH2k9PwjS5IQRpXSVBApUCZl1
server  |                 i64M38SCzlErW"
server  |             DNS name: "COBmHTK0GqlRDVMAVUzlkmUgJhDhJ072Qvr4jnEKi3WDoflZbZdY56
server  |                 iosvsKeY2iZjzYfdqWMMSkyNLY5Ovxf57Gqg3IysCmA5xlaxVn8uZgv6ayDOl
server  |                 MI3Qn2GfNIZi6FicOdj6ZSpV0CkOLEoHVyFwGQN5ScX2Nf1fYLggHcgfbt1gK
server  |                 QtV7cc1EC0KlX1cJjp9YzWlEwUoeSOoaPhUF3vNpgEweZcUsBBYH20veEA0Ll
server  |                 XreVs8dxQV9oo"
server  |             DNS name: "VWSll5n4BO2bYkpGG1GkqFILeXnjxEMrVQeCCLxDnxPxcl9KMR0cc1
server  |                 Yzh6NpYnrdgWF9lwtck9UUWROjjngSCAYJbisA7SZiENnfCHq9aZ3nOY3zaMh
server  |                 AFKLs3dTkQt76IriffhY1l2FY325Ga4FTAX8qJI9AUKIXagvMOyGiGT6GknIy
server  |                 G9qban5PenamQvSdr6q4vPhF1C2EsjYqOzxpp1meu6vw93Kck4LV7aaRbXX4b
server  |                 SWLdRvmQ60e0q"
server  |             DNS name: "901xGlsO28RRY3aNiwSZkELsyiw1AXl9IChooc9zxA6wFttQyOzwoI
server  |                 HEXZoY3PaRlfkElxu3i054RnmSXLgJAbAy5i7jsIH4E5RypBYjJ7wznEg3EtP
server  |                 SvKoue4YzUBYVit2jzOpXx0fB35oOMg0NQtP1aP4fs1yHscItmka7uvgqB32i
server  |                 vgnKjmieJJ3y43laSLecb0hMOT1kvHOAfTrp9MAOG5m89629YGJGLOhNWVoh7
server  |                 AHdap6N2z7CTK"
server  |             DNS name: "XpKspS7ek45ONyKcZk90AIQbHLj4ToN7Cu7hNSQbrO3g28PEPzooEg
server  |                 pSDeRUfrcDd8LgCtS2R7ssF7WmT9ZDWNy0Khnjs2RtjkIWnKjbzAYX4RVafzu
server  |                 NFz4dXsMQL8VRfSKyXwRIOvq3x59DQOv1NIloyKn1PODBuEnC6ssiboDiEIBq
server  |                 YrcaxZ1XgDgGfD5hJqjzXtswZCAPnhQzDLtJD42neZpPbpbbWLueVTw9aL2g3
server  |                 Upqq1t0BKDqky"
server  |             DNS name: "7ogNhqImRxdgNWENE38Iqnpt3qKXfsgPzugYOnEHzkiEXlMSUppZrX
server  |                 TVYLa2LC5dqy9OlzeqsR0dblN2D5C5JlQONhlzqJYEa4T27xM4mFHVbk1ErGi
server  |                 j8g0jxbMj7JJDvuHRI5fjAUJIVyvrs5btePwOCWgEWnYgehNv62vsU4uwGhQN
server  |                 Q9u79Q30EaGB7NcDYuvH3Ob13UtbPH69f7ZoRN10BGwJ97pKaB6VtCI9gvGbZ
server  |                 2pVGGa8VTQ3kF"
server  |             DNS name: "wNnEdJ1ANhTlV306nOF2S7JTEatzS9hpD75bVnSqUZ9MHI1VF7WhYl
server  |                 ipiIj6T8g41jrhW62aK08aYTIxX7oOJBU7Xs7Sv92lGsm4UilupjNFMVxxvyg
server  |                 F1jaZEVk2aGiBQudPD1EnDwnmidMszljexOf1Gk57TVJU6MtyGLEYPPupAdN5
server  |                 xIbTmKpT1Pqh5WcGP64qlpJActmtBQXPjiFHFffXcBcv0KgzXDN9X1sKcgDMu
server  |                 UFgFxa2nB5QES"
server  |             DNS name: "h8vwctPzEa4lCHjVsDjrlDLCUmYlY5JeFPZCsg2jXawpFd647dGViE
server  |                 kEyyMfxCOQ4CGDPqpEwSrJIOlUEEt9ATsMgMJPGPxIvxCCSzi6aenJz6E2dY3
server  |                 DxdyTDjRPsdAiz9qvbt1YMYu6XIYyeFeorB3A7lj1oGyRCylPZmlC3PFgLs7h
server  |                 MyCS5Ti4xoMAr7xQKS1ls7RNU35iJNo2n3R1gq2iikDXQcV1sdWnufkb3deBy
server  |                 erWDRGjQuwDOF"
server  |             DNS name: "jPtZt6eweAs9Qw737JcelVc4meWH257r6LdCSWbgk29gfl84pXlh8A
server  |                 sR2MfCIkOIZlDAam2AEWVshYSeO0nPCFSETUs2N3DPoYmP6OA9vQ0xRp01nog
server  |                 SslA6q2DXD39zCu9ETVOHeNw2wiWPNc4SIwOY0PmPdmbDiXBna54nwbNFwro5
server  |                 mMDGH3T0pcMNusVwV7iXt6sbPqR85I9yowm5Iuin1ZtdmgVaxKYRvHV9msNry
server  |                 mRSHChcoIRwCN"
server  | 
server  |             Name: Certificate Subject Key ID
server  |             Data:
server  |                 52:3c:aa:1b:ff:37:be:d5:fd:e7:4d:dd:93:5a:5c:f9:
server  |                 0b:21:5b:49
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 88:f6:e3:0e:9c:20:49:24:32:2c:46:2c:3a:04:cc:d8:
server  |                 71:6a:ac:16
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:44:02:20:72:a2:e4:0b:b7:30:73:55:38:88:37:ca:
server  |         6d:2d:55:83:e8:44:7f:23:3f:37:87:bf:5e:26:f2:d6:
server  |         d0:14:02:3c:02:20:2a:f2:0e:d5:55:9d:7d:44:29:92:
server  |         73:e0:be:85:6c:15:e7:7f:76:16:17:12:35:eb:0d:6b:
server  |         35:be:1c:b7:ef:2d
server  |     Fingerprint (SHA-256):
server  |         DE:95:AF:3B:7B:E9:5E:28:C5:5A:42:F6:01:1F:43:88:B1:13:33:41:EE:8A:B7:EF:7D:23:60:2B:5A:CE:34:2C
server  |     Fingerprint (SHA1):
server  |         7E:79:4E:AD:52:71:61:E9:80:8F:CE:1D:9A:CB:74:A9:C1:B0:28:C2
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.741 DEBUG [Server] Unsupported version: 57414954
server  | 0.741 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36354 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.034414114s
server  | 0.742 DEBUG [Server] Unsupported version: 57414954
server  | 0.742 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36354 IpTos(Cs0, NotEct) len 15
server  | 0.742 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.742 DEBUG [Server] Unsupported version: 57414954
server  | 0.742 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36354 IpTos(Cs0, NotEct) len 15
server  | 0.742 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 eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | Starting client
server  | 1.154 DEBUG [Server] Handle initial
server  | 1.154 INFO AddressValidation: no token; accepting
server  | 1.154 INFO [Server] Accept connection CID [8]: 106bc2134d132542
server  | 1.154 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.158 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.158 INFO [Server ...] Received valid Initial packet with scid CID [8]: e476d79ee3b94c80 dcid CID [8]: 106bc2134d132542
server  | 1.158 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=106bc2134d132542
server  | 1.158 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.158 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.158 DEBUG [Server 106bc2134d132542] State change from Init -> WaitInitial
server  | 1.158 DEBUG [Server 106bc2134d132542] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 530
server  |   -> RX Crypto { offset: 0, len: 482 }
server  | 1.158 DEBUG Read Ok(482) bytes
server  | 1.158 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.158 DEBUG Read secret available for Handshake: SymKey [32]: 36842b24738f5083b21b41cf589611d1a9716e4e63e0e1f22d8a833fc50c082c
server  | 1.158 DEBUG Write secret available for Handshake: SymKey [32]: 66c5686f0650f7214ed70cc8810dfe1275bf242bdb6fdc6344070ff75b30e583
server  | 1.158 DEBUG Writing transport parameters, msg=8
server  | 1.159 DEBUG Read secret available for ApplicationData: SymKey [32]: 9a64100016d49ef9b88889327e964ff17f9330c3007b61526f5382c4070f8e2d
server  | 1.159 DEBUG Write secret available for ApplicationData: SymKey [32]: 0945b0aff6422b5281b28bb29886a7b401f2fed53a33684d27ed194a2e9a6d6f
server  | 1.159 DEBUG [Agent 0x55d7095301d0] state -> InProgress
server  | 1.159 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.159 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.159 DEBUG [Crypto] Handshake keys installed
server  | 1.159 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.159 DEBUG [Crypto] Application write key installed
server  | 1.159 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 656, tv_nsec: 601149500 }
server  | 1.159 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:59592] Make permanent
server  | 1.159 DEBUG [unv-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] set as primary path
server  | 1.159 DEBUG [Server 106bc2134d132542] State change from WaitInitial -> Handshaking
server  | 1.159 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1
server  | 1.159 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.159 DEBUG Building Initial dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG CRYPTO for in offset=0, len=90
server  | 1.159 DEBUG [Server 106bc2134d132542] pn=0 type=Initial pri-unv-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 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  | 1.159 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG CRYPTO for hs offset=0, len=1040
server  | 1.159 DEBUG [Server 106bc2134d132542] pn=0 type=Handshake pri-unv-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 0, len: 1040 }
server  | 1.159 DEBUG packet_sent this=0x55d70952fb40, pn=0, ps=1088
server  | 1.159 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 1.159 DEBUG packet_sent this=0x55d70952fb40, pn=0, ps=144
server  | 1.159 DEBUG ECN probing: sent 1 probes
server  | 1.159 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.159 DEBUG Building Initial dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG CRYPTO for hs offset=1040, len=1183
server  | 1.159 DEBUG [Server 106bc2134d132542] pn=1 type=Handshake pri-unv-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1040, len: 1183 }
server  | 1.159 DEBUG packet_sent this=0x55d70952fb40, pn=1, ps=1232
server  | 1.159 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 1.159 DEBUG ECN probing: sent 2 probes
server  | 1.159 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false }
server  | 1.159 DEBUG Building Initial dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG CRYPTO for hs offset=2223, len=1087
server  | 1.159 DEBUG [Server 106bc2134d132542] pn=2 type=Handshake pri-unv-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1136
server  |   TX -> Crypto { offset: 2223, len: 1087 }
server  | 1.159 DEBUG packet_sent this=0x55d70952fb40, pn=2, ps=1136
server  | 1.159 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 1.159 DEBUG ECN probing: sent 3 probes
server  | 1.159 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.159 DEBUG Building Initial dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 1.159 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.159 DEBUG [Server 106bc2134d132542] delay duration 29.9950697s
server  | 1.159 DEBUG Setting timeout of 29.9950697s
server  | 1.159 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.159 DEBUG Building Initial dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 1.159 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 1.159 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.159 DEBUG [Server 106bc2134d132542] delay duration 29.995039223s
server  | 1.159 DEBUG Setting timeout of 29.995039223s
sim     | Dropping packet 2 (1200 bytes) from 193.167.0.100
sim     | Dropping packet 3 (50 bytes) from 193.167.0.100
sim     | Dropping packet 4 (50 bytes) from 193.167.0.100
sim     | Dropping packet 5 (47 bytes) from 193.167.0.100
sim     | Dropping packet 6 (47 bytes) from 193.167.0.100
sim     | Dropping packet 7 (47 bytes) from 193.167.0.100
server  | 3.371 DEBUG [Server 106bc2134d132542] Drop packet number space in
server  | 3.371 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 3.371 INFO [pri-unv-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] discarding a packet without an RTT estimate; guessing RTT=2.216592683s
server  | 3.371 DEBUG [Server 106bc2134d132542] pn=8 type=Handshake pri-unv-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 47
server  |   -> RX Ping
server  |   -> RX Padding { len: 1 }
server  | 3.371 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 658, tv_nsec: 817742183 }
server  | 3.371 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 658, tv_nsec: 817742183 }
server  | 3.371 DEBUG [pri-unv-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 658, tv_nsec: 817742183 }
server  | 3.371 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.371 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.371 DEBUG CRYPTO for hs offset=0, len=1179
server  | 3.371 DEBUG [Server 106bc2134d132542] pn=3 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Ack { largest_acknowledged: 8, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 1179 }
server  | 3.371 DEBUG packet_sent this=0x55d70952fb40, pn=3, ps=1232
server  | 3.371 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.371 DEBUG ECN probing: sent 4 probes
server  | 3.371 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.371 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.371 DEBUG CRYPTO for hs offset=1179, len=1183
server  | 3.371 DEBUG [Server 106bc2134d132542] pn=4 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 1179, len: 1183 }
server  | 3.371 DEBUG packet_sent this=0x55d70952fb40, pn=4, ps=1232
server  | 3.371 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.371 DEBUG ECN probing: sent 5 probes
server  | 3.371 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.371 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.371 DEBUG CRYPTO for hs offset=2362, len=1183
server  | 3.371 DEBUG [Server 106bc2134d132542] pn=5 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 2362, len: 1183 }
server  | 3.371 DEBUG packet_sent this=0x55d70952fb40, pn=5, ps=1232
server  | 3.371 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.371 DEBUG ECN probing: sent 6 probes
server  | 3.371 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.371 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.371 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.371 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.371 DEBUG [Server 106bc2134d132542] delay duration 110.462856ms
server  | 3.371 DEBUG Setting timeout of 110.462856ms
server  | 3.371 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.371 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.371 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.371 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.371 DEBUG [Server 106bc2134d132542] delay duration 110.426488ms
server  | 3.371 DEBUG Setting timeout of 110.426488ms
server  | 3.403 DEBUG [Server 106bc2134d132542] pn=9 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 37, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 3.403 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=3]
server  | 3.403 DEBUG [LossRecovery] ACK for Handshake - largest_acked=3
server  | 3.403 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=2464, cwnd=12320, state=SlowStart, new_acked=4688
server  | 3.403 DEBUG Acked crypto frame space=hs offset=0 length=1179
server  | 3.403 DEBUG Acked crypto frame space=hs offset=2223 length=1087
server  | 3.403 DEBUG Acked crypto frame space=hs offset=1040 length=1183
server  | 3.403 DEBUG Acked crypto frame space=hs offset=0 length=1040
server  | 3.403 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 658, tv_nsec: 849865989 }
server  | 3.403 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.403 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.403 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.403 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.403 DEBUG [Server 106bc2134d132542] delay duration 65.158574ms
server  | 3.403 DEBUG Setting timeout of 65.158574ms
server  | 3.403 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.403 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.403 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.403 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.403 DEBUG [Server 106bc2134d132542] delay duration 65.03277ms
server  | 3.403 DEBUG Setting timeout of 65.03277ms
server  | 3.404 DEBUG [Server 106bc2134d132542] pn=10 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 18, first_ack_range: 4, ack_ranges: [], ecn_count: None }
server  | 3.404 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=4]
server  | 3.404 DEBUG [LossRecovery] ACK for Handshake - largest_acked=4
server  | 3.404 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.404 DEBUG Acked crypto frame space=hs offset=1179 length=1183
server  | 3.404 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 658, tv_nsec: 850657282 }
server  | 3.404 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.404 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.404 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.404 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.404 DEBUG [Server 106bc2134d132542] delay duration 52.452583ms
server  | 3.404 DEBUG Setting timeout of 52.452583ms
server  | 3.404 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.404 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.404 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.404 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.404 DEBUG [Server 106bc2134d132542] delay duration 52.394294ms
server  | 3.404 DEBUG Setting timeout of 52.394294ms
server  | 3.405 DEBUG [Server 106bc2134d132542] pn=11 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 9, first_ack_range: 5, ack_ranges: [], ecn_count: None }
server  | 3.405 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=5]
server  | 3.405 DEBUG [LossRecovery] ACK for Handshake - largest_acked=5
server  | 3.405 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.405 DEBUG Acked crypto frame space=hs offset=2362 length=1183
server  | 3.405 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 658, tv_nsec: 851580652 }
server  | 3.405 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.405 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.405 DEBUG CRYPTO for hs offset=3545, len=1183
server  | 3.405 DEBUG [Server 106bc2134d132542] pn=6 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 3545, len: 1183 }
server  | 3.405 DEBUG packet_sent this=0x55d70952fb40, pn=6, ps=1232
server  | 3.405 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.405 DEBUG ECN probing: sent 7 probes
server  | 3.405 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.405 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.405 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.405 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.405 DEBUG [Server 106bc2134d132542] delay duration 74.684887ms
server  | 3.405 DEBUG Setting timeout of 74.684887ms
server  | 3.405 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.405 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.405 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.405 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.405 DEBUG [Server 106bc2134d132542] delay duration 74.658097ms
server  | 3.405 DEBUG Setting timeout of 74.658097ms
server  | 3.436 DEBUG [Server 106bc2134d132542] pn=12 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 12, first_ack_range: 6, ack_ranges: [], ecn_count: None }
server  | 3.437 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=6]
server  | 3.437 DEBUG [LossRecovery] ACK for Handshake - largest_acked=6
server  | 3.437 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.437 DEBUG Acked crypto frame space=hs offset=3545 length=1183
server  | 3.437 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 658, tv_nsec: 883378992 }
server  | 3.437 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.437 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.437 DEBUG CRYPTO for hs offset=4728, len=1183
server  | 3.437 DEBUG [Server 106bc2134d132542] pn=7 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 4728, len: 1183 }
server  | 3.437 DEBUG packet_sent this=0x55d70952fb40, pn=7, ps=1232
server  | 3.437 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.437 DEBUG ECN probing: sent 8 probes
server  | 3.437 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.437 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.437 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.437 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.437 DEBUG [Server 106bc2134d132542] delay duration 65.502402ms
server  | 3.437 DEBUG Setting timeout of 65.502402ms
server  | 3.437 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.437 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.437 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.437 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.437 DEBUG [Server 106bc2134d132542] delay duration 65.470172ms
server  | 3.437 DEBUG Setting timeout of 65.470172ms
server  | 3.468 DEBUG [Server 106bc2134d132542] pn=13 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 11, first_ack_range: 7, ack_ranges: [], ecn_count: None }
server  | 3.468 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=7]
server  | 3.468 DEBUG [LossRecovery] ACK for Handshake - largest_acked=7
server  | 3.468 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.468 DEBUG Acked crypto frame space=hs offset=4728 length=1183
server  | 3.468 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 658, tv_nsec: 915188462 }
server  | 3.468 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.468 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.468 DEBUG CRYPTO for hs offset=5911, len=1183
server  | 3.468 DEBUG [Server 106bc2134d132542] pn=8 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 5911, len: 1183 }
server  | 3.468 DEBUG packet_sent this=0x55d70952fb40, pn=8, ps=1232
server  | 3.468 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.468 DEBUG ECN probing: sent 9 probes
server  | 3.468 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.468 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.468 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.468 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.468 DEBUG [Server 106bc2134d132542] delay duration 57.480681ms
server  | 3.468 DEBUG Setting timeout of 57.480681ms
server  | 3.468 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.468 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.468 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.468 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.468 DEBUG [Server 106bc2134d132542] delay duration 57.445295ms
server  | 3.468 DEBUG Setting timeout of 57.445295ms
server  | 3.500 DEBUG [Server 106bc2134d132542] pn=14 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 10, first_ack_range: 8, ack_ranges: [], ecn_count: None }
server  | 3.500 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=8]
server  | 3.500 DEBUG [LossRecovery] ACK for Handshake - largest_acked=8
server  | 3.500 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.500 DEBUG Acked crypto frame space=hs offset=5911 length=1183
server  | 3.500 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 658, tv_nsec: 947027369 }
server  | 3.500 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.500 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.500 DEBUG CRYPTO for hs offset=7094, len=1183
server  | 3.500 DEBUG [Server 106bc2134d132542] pn=9 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Crypto { offset: 7094, len: 1183 }
server  | 3.500 DEBUG packet_sent this=0x55d70952fb40, pn=9, ps=1232
server  | 3.500 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.500 DEBUG ECN probing: sent 10 probes
server  | 3.500 DEBUG ECN probing concluded with 10 probes sent
server  | 3.500 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.500 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.500 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.500 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.500 DEBUG [Server 106bc2134d132542] delay duration 50.514763ms
server  | 3.500 DEBUG Setting timeout of 50.514763ms
server  | 3.500 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.500 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.500 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.500 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.500 DEBUG [Server 106bc2134d132542] delay duration 50.478736ms
server  | 3.500 DEBUG Setting timeout of 50.478736ms
server  | 3.532 DEBUG [Server 106bc2134d132542] pn=15 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 10, first_ack_range: 9, ack_ranges: [], ecn_count: None }
server  | 3.532 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=9]
server  | 3.532 DEBUG [LossRecovery] ACK for Handshake - largest_acked=9
server  | 3.532 WARN ECN validation failed, no ECN counts in ACK frame
server  | 3.532 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.532 DEBUG Acked crypto frame space=hs offset=7094 length=1183
server  | 3.532 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 658, tv_nsec: 978818546 }
server  | 3.532 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.532 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.532 DEBUG CRYPTO for hs offset=8277, len=1183
server  | 3.532 DEBUG [Server 106bc2134d132542] pn=10 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Crypto { offset: 8277, len: 1183 }
server  | 3.532 DEBUG packet_sent this=0x55d70952fb40, pn=10, ps=1232
server  | 3.532 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.532 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.532 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.532 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.532 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.532 DEBUG [Server 106bc2134d132542] delay duration 44.38546ms
server  | 3.532 DEBUG Setting timeout of 44.38546ms
server  | 3.532 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.532 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.532 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.532 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.532 DEBUG [Server 106bc2134d132542] delay duration 44.349984ms
server  | 3.532 DEBUG Setting timeout of 44.349984ms
server  | 3.564 DEBUG [Server 106bc2134d132542] pn=16 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 13, first_ack_range: 10, ack_ranges: [], ecn_count: None }
server  | 3.564 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=10]
server  | 3.564 DEBUG [LossRecovery] ACK for Handshake - largest_acked=10
server  | 3.564 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.564 DEBUG Acked crypto frame space=hs offset=8277 length=1183
server  | 3.564 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 659, tv_nsec: 10666511 }
server  | 3.564 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.564 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.564 DEBUG CRYPTO for hs offset=9460, len=507
server  | 3.564 DEBUG [Server 106bc2134d132542] pn=11 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 556
server  |   TX -> Crypto { offset: 9460, len: 507 }
server  | 3.564 DEBUG packet_sent this=0x55d70952fb40, pn=11, ps=556
server  | 3.564 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.564 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.564 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.564 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.564 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.564 DEBUG [Server 106bc2134d132542] delay duration 24.822621ms
server  | 3.564 DEBUG Setting timeout of 24.822621ms
server  | 3.564 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.564 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.564 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.564 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.564 DEBUG [Server 106bc2134d132542] delay duration 24.789209ms
server  | 3.564 DEBUG Setting timeout of 24.789209ms
server  | 3.590 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.590 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.590 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.590 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.590 DEBUG [Server 106bc2134d132542] delay duration 5.456173s
server  | 3.590 DEBUG Setting timeout of 5.456173s
server  | 3.590 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.590 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.590 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.590 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.590 DEBUG [Server 106bc2134d132542] delay duration 5.456135901s
server  | 3.590 DEBUG Setting timeout of 5.456135901s
server  | 3.596 DEBUG [Server 106bc2134d132542] pn=17 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 91
server  |   -> RX Ack { largest_acknowledged: 11, ack_delay: 155, first_ack_range: 11, ack_ranges: [], ecn_count: None }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 3.597 DEBUG [Server 106bc2134d132542] Rx ACK space=hs, ranges=[0..=11]
server  | 3.597 DEBUG [LossRecovery] ACK for Handshake - largest_acked=11
server  | 3.597 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=556
server  | 3.597 DEBUG Acked crypto frame space=hs offset=9460 length=507
server  | 3.597 DEBUG Read Ok(36) bytes
server  | 3.597 DEBUG [Agent 0x55d7095301d0] 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.597 DEBUG [Server 106bc2134d132542] TLS connection complete
server  | 3.597 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 659, tv_nsec: 43357182 }
server  | 3.597 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 3.597 DEBUG [Crypto] application read keys installed
server  | 3.597 DEBUG [Server 106bc2134d132542] State change from Handshaking -> Connected
server  | 3.597 DEBUG [Server 106bc2134d132542] State change from Connected -> Confirmed
server  | 3.597 DEBUG PMTUD started with probe size 1380
server  | 3.597 INFO [Server 106bc2134d132542] Connection established
server  | 3.597 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 659, tv_nsec: 43357182 }
server  | 3.597 DEBUG [Server 106bc2134d132542] pn=18 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 225
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [60, 134, 209, 65, 32, 24, 210, 63], stateless_reset_token: [113, 63, 161, 236, 192, 5, 154, 78, 53, 176, 176, 54, 203, 56, 241, 140] }
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [246, 203, 35, 163, 246, 52, 18, 63], stateless_reset_token: [150, 55, 62, 242, 81, 95, 250, 113, 23, 190, 235, 93, 161, 255, 93, 251] }
server  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [182, 88, 151, 218, 91, 199, 97, 103], stateless_reset_token: [55, 85, 138, 83, 10, 56, 57, 48, 43, 82, 201, 214, 1, 157, 236, 108] }
server  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [181, 213, 148, 175, 191, 71, 8, 90], stateless_reset_token: [238, 244, 246, 56, 227, 164, 222, 137, 164, 146, 228, 171, 170, 184, 196, 39] }
server  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [97, 61, 79, 67, 144, 6, 56, 154], stateless_reset_token: [107, 58, 145, 156, 40, 252, 8, 231, 61, 182, 169, 58, 148, 255, 16, 136] }
server  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [246, 230, 109, 9, 142, 90, 109, 209], stateless_reset_token: [3, 75, 215, 12, 116, 44, 40, 209, 128, 173, 248, 51, 164, 145, 154, 184] }
server  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [33, 129, 28, 8, 42, 40, 132, 104], stateless_reset_token: [236, 160, 40, 105, 31, 233, 154, 15, 178, 77, 132, 204, 73, 201, 72, 98] }
server  | 3.597 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 659, tv_nsec: 43357182 }
server  | 3.597 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.597 DEBUG Building Handshake dcid Some(CID [8]: e476d79ee3b94c80) scid Some(CID [10]: a933d539c55c84065380)
server  | 3.597 DEBUG [Server 106bc2134d132542] pn=12 type=Handshake pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 49
server  |   TX -> Ack { largest_acknowledged: 17, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: None }
server  | 3.597 DEBUG [Server 106bc2134d132542] Drop packet number space hs
server  | 3.597 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 3.597 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.597 DEBUG [Server 106bc2134d132542] pn=0 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 1332
server  |   TX -> Ack { largest_acknowledged: 18, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [160, 86, 187, 243, 101, 62, 224, 89, 28, 95], stateless_reset_token: [92, 85, 242, 60, 38, 218, 123, 49, 240, 186, 228, 193, 64, 97, 240, 240] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [168, 149, 130, 250, 31, 59, 249, 41, 178, 109], stateless_reset_token: [130, 61, 4, 73, 97, 125, 9, 182, 178, 90, 78, 254, 232, 117, 232, 5] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [81, 109, 216, 84, 128, 251, 206, 180, 141, 93], stateless_reset_token: [158, 252, 30, 37, 13, 93, 51, 91, 78, 43, 216, 159, 225, 80, 98, 251] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [134, 57, 242, 145, 126, 11, 133, 210, 184, 131], stateless_reset_token: [211, 164, 246, 238, 91, 174, 53, 40, 40, 87, 236, 42, 200, 31, 95, 101] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [177, 220, 103, 81, 113, 126, 182, 230, 6, 176], stateless_reset_token: [53, 196, 174, 156, 180, 185, 192, 39, 38, 141, 151, 29, 27, 83, 173, 254] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [98, 166, 199, 29, 111, 133, 139, 52, 111, 86], stateless_reset_token: [24, 73, 177, 219, 55, 143, 106, 117, 138, 19, 112, 126, 195, 210, 221, 245] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [96, 59, 127, 2, 203, 164, 86, 28, 192, 111], stateless_reset_token: [198, 44, 135, 108, 55, 59, 190, 124, 236, 123, 86, 184, 39, 232, 22, 129] }
server  |   TX -> Padding { len: 1041 }
server  | 3.597 DEBUG packet_sent this=0x55d70952fb40, pn=0, ps=1283
server  | 3.597 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.597 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.597 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.597 DEBUG [Server 106bc2134d132542] delay duration 25.932706ms
server  | 3.597 DEBUG Setting timeout of 25.932706ms
server  | 3.597 DEBUG [Server 106bc2134d132542] pn=19 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 50
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 3.597 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 659, tv_nsec: 63989420 }
server  | 3.597 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.597 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.597 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.597 DEBUG [Server 106bc2134d132542] delay duration 20ms
server  | 3.597 DEBUG Setting timeout of 20ms
server  | 3.598 DEBUG [Server 106bc2134d132542] send session ticket 40630008106bc2134d1325420104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0aa933d539c55c84065380110c00000001aa4a0aea000000016ab200c0000000ff02de1a0243e8200100686921
server  | 3.598 DEBUG Path = 'srjxfeupbr'
server  | 3.598 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.598 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.598 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.598 DEBUG [Server 106bc2134d132542] delay duration 18.794736ms
server  | 3.598 DEBUG Setting timeout of 18.794736ms
server  | 3.618 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.618 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.618 DEBUG [Server 106bc2134d132542] pn=1 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 32
server  |   TX -> Ack { largest_acknowledged: 19, ack_delay: 2663, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 3.618 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.618 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.618 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.618 DEBUG [Server 106bc2134d132542] delay duration 5.442154ms
server  | 3.618 DEBUG Setting timeout of 5.442154ms
server  | 3.619 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.619 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.619 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.619 DEBUG [Server 106bc2134d132542] delay duration 5.415073ms
server  | 3.619 DEBUG Setting timeout of 5.415073ms
server  | 3.625 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.625 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.625 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 3.625 DEBUG [Server 106bc2134d132542] pn=2 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 1332
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1303, fin: false }
server  | 3.625 DEBUG packet_sent this=0x55d70952fb40, pn=2, ps=1332
server  | 3.625 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.625 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.625 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.625 DEBUG [Server 106bc2134d132542] delay duration 34.370975ms
server  | 3.625 DEBUG Setting timeout of 34.370975ms
server  | 3.625 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.625 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.625 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.625 DEBUG [Server 106bc2134d132542] delay duration 34.345258ms
server  | 3.625 DEBUG Setting timeout of 34.345258ms
server  | 3.630 DEBUG [Server 106bc2134d132542] pn=20 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 35
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 193, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 3.630 DEBUG [Server 106bc2134d132542] Rx ACK space=ap, ranges=[0..=0]
server  | 3.630 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0
server  | 3.630 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=1332, cwnd=12320, state=SlowStart, new_acked=1283
server  | 3.630 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.630 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.630 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.630 DEBUG [Server 106bc2134d132542] delay duration 24.553977ms
server  | 3.630 DEBUG Setting timeout of 24.553977ms
server  | 3.630 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.630 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.630 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.630 DEBUG [Server 106bc2134d132542] delay duration 24.499686ms
server  | 3.630 DEBUG Setting timeout of 24.499686ms
server  | 3.656 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.656 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.656 DEBUG [Server 106bc2134d132542] pn=3 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1303, len: >>1202, fin: false }
server  | 3.656 DEBUG packet_sent this=0x55d70952fb40, pn=3, ps=1232
server  | 3.656 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.656 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.656 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.656 DEBUG [Server 106bc2134d132542] delay duration 29.627265ms
server  | 3.656 DEBUG Setting timeout of 29.627265ms
server  | 3.656 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.656 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.656 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.656 DEBUG [Server 106bc2134d132542] delay duration 29.601818ms
server  | 3.656 DEBUG Setting timeout of 29.601818ms
server  | 3.657 DEBUG [Server 106bc2134d132542] pn=21 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 35
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 151, first_ack_range: 2, ack_ranges: [], ecn_count: None }
server  | 3.657 DEBUG [Server 106bc2134d132542] Rx ACK space=ap, ranges=[0..=2]
server  | 3.657 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 3.657 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1332
server  | 3.657 DEBUG PMTUD probe of size 1380 succeeded
server  | 3.657 DEBUG PMTUD started with probe size 1420
server  | 3.657 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 3.657 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.657 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.657 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.657 DEBUG [Server 106bc2134d132542] delay duration 26.544468ms
server  | 3.657 DEBUG Setting timeout of 26.544468ms
server  | 3.657 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.657 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.657 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.657 DEBUG [Server 106bc2134d132542] delay duration 26.498352ms
server  | 3.657 DEBUG Setting timeout of 26.498352ms
server  | 3.685 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 3.685 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.685 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 3.685 DEBUG [Server 106bc2134d132542] pn=4 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 2505, len: >>1341, fin: false }
server  | 3.685 DEBUG packet_sent this=0x55d70952fb40, pn=4, ps=1372
server  | 3.685 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.685 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.685 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.685 DEBUG [Server 106bc2134d132542] delay duration 28.847017ms
server  | 3.685 DEBUG Setting timeout of 28.847017ms
server  | 3.685 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.685 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.685 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.685 DEBUG [Server 106bc2134d132542] delay duration 28.82691ms
server  | 3.685 DEBUG Setting timeout of 28.82691ms
server  | 3.688 DEBUG [Server 106bc2134d132542] pn=22 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 35
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 153, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 3.688 DEBUG [Server 106bc2134d132542] Rx ACK space=ap, ranges=[0..=3]
server  | 3.688 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
server  | 3.688 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=1372, cwnd=12320, state=SlowStart, new_acked=1232
server  | 3.688 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.688 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.688 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.688 DEBUG [Server 106bc2134d132542] delay duration 21.661177ms
server  | 3.688 DEBUG Setting timeout of 21.661177ms
server  | 3.688 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.688 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.688 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.688 DEBUG [Server 106bc2134d132542] delay duration 21.601175ms
server  | 3.688 DEBUG Setting timeout of 21.601175ms
server  | 3.711 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 3.711 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.711 DEBUG CRYPTO for ap offset=0, len=23
server  | 3.711 DEBUG [Server 106bc2134d132542] pn=5 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 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.711 DEBUG packet_sent this=0x55d70952fb40, pn=5, ps=1332
server  | 3.711 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.711 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.711 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.711 DEBUG [Server 106bc2134d132542] delay duration 24.975106ms
server  | 3.711 DEBUG Setting timeout of 24.975106ms
server  | 3.711 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.711 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.711 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.711 DEBUG [Server 106bc2134d132542] delay duration 24.916407ms
server  | 3.711 DEBUG Setting timeout of 24.916407ms
server  | 3.717 DEBUG [Server 106bc2134d132542] pn=23 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 35
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 156, first_ack_range: 4, ack_ranges: [], ecn_count: None }
server  | 3.717 DEBUG [Server 106bc2134d132542] Rx ACK space=ap, ranges=[0..=4]
server  | 3.717 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
server  | 3.717 DEBUG on_packets_acked this=0x55d70952fb40, limited=1, bytes_in_flight=1332, cwnd=12320, state=SlowStart, new_acked=1372
server  | 3.717 DEBUG PMTUD probe of size 1420 succeeded
server  | 3.717 DEBUG PMTUD started with probe size 1470
server  | 3.717 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 3.717 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.717 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.717 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.717 DEBUG [Server 106bc2134d132542] delay duration 15.932213ms
server  | 3.717 DEBUG Setting timeout of 15.932213ms
server  | 3.718 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.718 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.718 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.718 DEBUG [Server 106bc2134d132542] delay duration 15.883382ms
server  | 3.718 DEBUG Setting timeout of 15.883382ms
server  | 3.735 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 3.735 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.735 DEBUG Sending PMTUD probe of size 1470, count 1
server  | 3.735 DEBUG CRYPTO for ap offset=23, len=282
server  | 3.735 DEBUG [Server 106bc2134d132542] pn=6 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 1422
server  |   TX -> Ping
server  |   TX -> Crypto { offset: 23, len: 282 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 245, 93, 164, 233, 64, 129, 31, 112, 89, 189, 253, 93, 171, 124, 84, 186, 186, 116, 19, 61, 151, 153, 165, 60, 25, 111, 215, 227, 142, 73, 247, 191, 43, 99, 144, 169] }
server  |   TX -> Padding { len: 1064 }
server  | 3.735 DEBUG packet_sent this=0x55d70952fb40, pn=6, ps=1422
server  | 3.735 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.735 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.735 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.735 DEBUG [Server 106bc2134d132542] delay duration 22.800453ms
server  | 3.735 DEBUG Setting timeout of 22.800453ms
server  | 3.735 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.735 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.735 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.735 DEBUG [Server 106bc2134d132542] delay duration 22.779163ms
server  | 3.735 DEBUG Setting timeout of 22.779163ms
server  | 3.743 DEBUG [Server 106bc2134d132542] pn=24 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 33
server  |   -> RX ConnectionClose { error_code: Application(256), frame_type: 0, reason_phrase: "" }
server  | 3.743 INFO [Server 106bc2134d132542] ConnectionClose received. Error code: Application(256) frame type 0 reason 
server  | 3.743 DEBUG [Server 106bc2134d132542] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(256)), timeout: Instant { tv_sec: 668, tv_nsec: 822804847 } }
server  | 3.743 DEBUG [pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592] Path validated Instant { tv_sec: 659, tv_nsec: 189778831 }
server  | 3.743 DEBUG [Server 106bc2134d132542] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.743 DEBUG Building Short dcid Some(CID [8]: e476d79ee3b94c80)
server  | 3.743 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 659, tv_nsec: 189778831 }
server  | 3.743 DEBUG [Server 106bc2134d132542] pn=7 type=Short pri-path:e476d79ee3b94c80 [::]:443->[::ffff:193.167.0.100]:59592 IpTos(Cs0, NotEct) len 35
server  |   TX -> Ack { largest_acknowledged: 24, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 3.743 DEBUG Setting timeout of 9.632909168s
server  | 3.743 DEBUG Setting timeout of 9.632895222s

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

2025-04-30 12:27:07,804 Using the client's key log file.
2025-04-30 12:27:07,813 
2025-04-30 12:27:07,813 Using the client's key log file.
2025-04-30 12:27:07,813 Using the client's key log file.
2025-04-30 12:27:07,821 
2025-04-30 12:27:07,822 Using the client's key log file.
2025-04-30 12:27:07,822 Using the client's key log file.
2025-04-30 12:27:08,460 Check of downloaded files succeeded.
2025-04-30 12:27:08,783 Server sent 9967 bytes in Handshake CRYPTO frames.
2025-04-30 12:27:09,145 Received a 1200 byte Initial packet from the client. Amplification limit: 3600
2025-04-30 12:27:09,145 Received a 1232 byte Handshake packet from the server. Total: 1232
2025-04-30 12:27:09,145 Received a 1232 byte Handshake packet from the server. Total: 2464
2025-04-30 12:27:09,145 Received a 1136 byte Handshake packet from the server. Total: 3600