2025-09-12 05:04:46,863 Generated random file: huwbglvzal of size: 5120 2025-09-12 05:04:46,863 Requests: https://server4:443/huwbglvzal 2025-09-12 05:04:47,257 2025-09-12 05:04:47,258 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_nzy09_ll/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_j1cdroe6/ DOWNLOADS=/tmp/download_thg4o4d7/ SERVER_LOGS=/tmp/logs_server_ln7myuwz CLIENT_LOGS=/tmp/logs_client_alm1l5iw SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=ghcr.io/alibaba/xquic/xquic-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/huwbglvzal" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-12 05:05:00,455 Container sim Recreate Container sim Recreated Container server Recreate Container client Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 server | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin server | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin server | + '[' -n transfer ']' server | + '[' -n /logs/qlog/ ']' server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert server | ++ mktemp server | + P12CERT=/tmp/tmp.DgrfLeJpV0 server | + mkdir -p /neqo/db client | Endpoint's IPv6 address is fd00:cafe:cafe::100 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.DgrfLeJpV0 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.DgrfLeJpV0 -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 | 29:cd:94:5d:30:aa:54:e6:45:66:4e:22:ff:31:07:2d: server | d5:a3:20:e9 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner intermediate 8" server | Validity: server | Not Before: Fri Sep 12 05:04:47 2025 server | Not After : Mon Sep 22 05:04:47 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:0d:81:f0:57:9f:b4:95:eb:f3:5c:ad:ba:2f:82:a2: server | 9e:d1:1c:70:03:c0:3b:3f:27:59:1e:fe:10:50:f1:d3: server | d1:6e:d8:7e:b9:12:2c:3f:8d:c1:1d:45:8b:3c:7e:24: server | f0:5c:b5:b5:bc:77:e6:53:c6:b1:d5:f7:5c:a1:12:16: server | 52 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: "uJUSgKzzURhnKbJ6lgwVVAIrMQzZhPpS5zzHKhAuYbi4qIMUsgfdJA server | R7ynqqXnrZEN8aRAC69njWNGltuEiMve2QHCVudKOACSu5033wsyx1ZxSnI79 server | eQarOcymZPKJztxrqr42q8aEJED4Cpv3nU2JLvM7Rm0abP3la2QnicS6VpLjg server | qlFkCN7sGPs5areBe0gXFWVsYlzJmSdTR1KJhTuY8iEM291wNVhlLCZ2chgaa server | FTeYHtAMUJUUm" server | DNS name: "oBiJIhUSWXcG6mXfsnorVtByL6AUL6EI8hzI5qovViEkaKHadeB1kN server | hJxGYAaPSADstxkAQPT2EEGSqCErmX0bHuHYq4QCfsGUZweBb4JGCd1YZT7mN server | eKe8gkvDZP1Y7x0Xklvm9021E8RsYUD7TMfMVD5FdJSzqAGyvWhOoRFWxZrav server | 9EmIGnU6l3DzDwOKjxIFWgrp3tIuj7iAUofwUgUFZDwPPLlS25rppztiJVPHt server | DcB9RG7OTKwbD" server | DNS name: "rhbDYdBGx7lucOqntB48RJKQmbuBLYRyJsJk1WcFaf2ezlOL4loSnP server | iVm3redb9vL5Z6VpqU97pkxr0McJ5LUYq0NZS9E79Z7MwpOG4qeViwBOh6vIB server | nXLHt4Q6Jv7hplOmafXK6TjOweVvGi5ETbxdSvxWZjpDTqMSCxNO2XlsZLVaZ server | 8dvtV1jUrJGZaMbS43iuWHBxlX1xrA2vklGzAop6NgrjJ6AgBzRWUtUxlNYA5 server | KTPSwXR7vK8c2" server | DNS name: "b4ppw7AJtu9FPVlmr3Alr6XY5Ogt61dTlvLIuaZ0JyBXs9CMehN1Lh server | NlBYTEuJyPigOJWh5Vs0KRzBWXsENYEoCeIWsuToxYjWZZWTLpvHBSjioKf26 server | 4628CHWN4ByeCFSWGGX9I6flrHN64sqs0wsHPga6LTZVqkbuyShMldTRhfYIo server | WkSPHYLAeJIq64k2o9M9BTxxWzD1xvsvC9wh18o2HlAkrb6YhPVkftl12YvX1 server | VlrDJcx6Szv3E" server | DNS name: "hRV7Rbc7NqeDGf5DkhTlQq43tt6kM7EGXbYRmIlJxRxYEMn4wG9Hsw server | g4ShQWoTtT0G4z4cQrdNs1rr5e8OfqRD8oUqoreVPCdLYsPiWpmfdpbTozr8s server | A1yPLDAYQTg8f1E0aUyiX7pUbRw0Wf1BJuJtNd0HfHDRS5a5xqYrpEPX90rIz server | qYh8BDBVTfP0OtlgaDutubbIncmUWNivXeW2cwg6FBuJT1hAVpX4mnSmLLc0Y server | e5Eutcg1vS2re" server | DNS name: "XJw8c85hJYCzVYqbOZ2wykF7nAEKOGW3GoObArTaZozgs9Ib8lg0rD server | ympunviZSyWeCPtUpnkxAdEaobqqu9p3au5Svpkx4mQjzgJmL0ajziVwrGuHt server | Ke2K1wI4IHNMz2GL8gD5ygzc91WmpNbdGXz5mjkdj4VsTxIxPyQfqRfN7JX88 server | b7uJU2g4hK74Txgur9b1yRt0tHZe61qlN2qzj571GAAfS9GERmhNU6Oy9KDDr server | HH3DQYHwEzOeh" server | DNS name: "H8ApY1p51AywA9ysGdIjVKaDDiBHyzDrZKz2jf3FsWuF4Rt9snCwse server | gKjbQnH5JVL2KMXG2iCJpuRhgwdLuWKm4FFq7t6YQX6lAgpyxdf1tO5sPzatC server | IxmSwrEuTTGIZFIIjpTD6rHaDoAvUoYCHfq2gy62F7z2MHQjwnjjmHZy642Mp server | RFqJMhTeE6Z5gPxyvBQlYBcF5B8ALAzMZO3FYQ9HBUzZNep8mRu8hUOsVCJXn server | p43lF9kMFPqQG" server | DNS name: "LPcMBh9PrjoDunvlWykABJTETg3maW65jy8WnfpSMbJJg7Tu2NW8sN server | z2yE0a4wthR7yeX7Fy0mNqTqtYwAVMAG43dvIoiOArBBRQY6VKCMJqXjmVa6F server | zRYQlbLXJOgV8wDIFHkXYcTdGIm04gaEfR5s053CuuWeGms6flwraATnmOuEs server | 46uSE1W0MSk9iL1i8WqC78HTuiihrijeCyElnl9iIvJobvkDve1834hjrdn1V server | 927KpA8vY5YyY" server | DNS name: "7Cr9COkuOWI4jgnKmQ4qLLaHg4oejXqhwU0wPOze6dlPi9q4lurk3D server | afc975AlM4YGfwBUsXl4ljUhftEq1lFIhO6NS2EkdjeQ4tIOYq7PmuBP8kLOv server | SYplicZMIMgelDRWMWLayxhav9GBkYX3cBi8rP9GIsPPZhONb4PqgQNrsbMSd server | RLDj1d2eFIUQdLKZoITwjciGvUVmNi7K7ywGCgZBnpIImdEwtOIBms4DqqIWX server | KUx5acphl3DLh" server | DNS name: "kBI0p0pS0mfQwj5MNr9jnFd4LrLNNBWXltZ6SXB7t2pVcNvBtNOmsq server | s2FetoMqCU5sudB5DlEEwlYCY3R6JY8wRkPKQ4pcIr9F1mK7tO98VFlb6Pyh9 server | ZyFS9kKBlrdaW1jlbgtsUrLPVtUSzqy2JAvrWnwQBhGXcviDf5OrdhL8zPjxd server | AgCZPMOqDJ9KTFL0DudMla9h7ef8hEVXsOfZob6dIVuLScClYxYHnBPa0QPP0 server | XZNTTMNJMGfYZ" server | DNS name: "lQrQa8T4RPEN0cPMLI6PfFJj0t7mbZXzo8iS0JXxZC3atnOMwvMBCG server | NWz6qEwjZ5M6k3HCJCwltq1VaTDzEf3SuCBCi9G0PQPIv7r4Qmk5tjUlrdxXc server | wknSUPaAjGrkIgti36wW5DBCo1hwfuxF6q96MdUK5uZyjTuPq5Ic4HcNk6LAd server | QIu33exfyhWp8Q7Mo6nB3OyLevPH6QYpKqBZXLyuRt8Ks3vzQikZ04OhWlTGr server | dXkJDFtY0zXJx" server | DNS name: "Pxs2aYTQ1jO28KQMZb6jnlNDWBc48WNmATYUhnYxYQoZDpzW7rVp9f server | T40hIUOUOohkvhnC8iTRWe7SiY1h3yJVA8nDlVr1MrswxVNY24kfiWoVqWs7o server | 1dgp2ho4MWQWiFaKaT2Qu9i30DjG5vJnFW0hVg9u7ZC57Na4ubaZ5W3mlUp1K server | D73S4w1vDiDbeUYvlVDwZQCO8RPZo3nw1z929SpM62GOWlmgzxUSlttQZHwTn server | qkDfiwcFexgyE" server | DNS name: "a19a5o839hnory6mUXFZu81OixaYzGTTMxMNnhfF0EGetsO7GfUkjY server | 8infibWIuUyeKB4K9ee0YgJDlHyiQLXCLiOaBh5ddQd1vx8nr9jHbTO6v5pzG server | GQFO0G9Uf9721hhK19BdFIhlYvaQKqLjpwqFQi6vnYhct14pRDu69HN6dShq7 server | gd0L6t7Yz6OkGdG2SceC7HYRK87OYK8L4uwLnJNeSZ0eYXbdEwMr4hqCSvlUd server | dBZ1XWdP1AayJ" server | DNS name: "vUN9MLcb3h2x4VnHNoSIKyFCPY2uiHwGSy8KziDD1FdHEqKi1Synn8 server | 3V0kGy0UaL5Cbmu6o1xzuNtojAiyI6di8shrIgVA1S8hKkk9PVy4nHNGKQiHS server | uGK1lIbMcu9IEGrIB9tt9I5E241JLTK1DpaKxDaIuxg8w1RXXp20bxtWGb8CJ server | wqR1EufuS2pIwngp2FwH1Rwu7Cmzwql7b6LrlkcxOLbvLbBe8SL4nxFnBuS91 server | 6VccEeQpBLoBh" server | DNS name: "5mMLIW4tERNUTh8mewxxpY9E0xiaBpGQx4B3G8vtO7gz0HRQEAO2xJ server | XICw0YemMBJTPv154W93hc5knyQfsfuwhtLqWRsVCivHg47rX6DoboYjoNMcH server | Ey0RDnWYs1YYBElSyTGDLdRJoUbBiZwhDLhCDQzJoaGDAIX8gxR6Qdrl9uaGH server | C8payL4QxVMTB0Na32K4cHaL5A9vjzmKhVq1Fq3IcU2jDWZswoftdx3nr2Q0X server | tedDrknv7Tikd" server | DNS name: "JmcYXVglDcLbEygqSCEJfG4lDivCxFnYzXVhb8pEhNRCuzXIOPPt3L server | c5L2ICn9lOkOYOLKR2zmh4YDhrNkUIL9dVaAYnRZNYquT42LXGGZUBsa9eRv3 server | SiDRaReYUgPoUMlJk3z7iMUBl6xuaowT2dHKKGFyxy00yeVlbYZRaDzLjOx7Z server | 6Q0vt7x7ZHGkKPAF0JNzSbllZwdit0X1vRC0xZlLBchntkT4CqeT74znA1ri1 server | 6x3wpvNHnjT2r" server | DNS name: "VfrnyBVPsxa1F0CSFStpIxCWeY8HPEskyTuPMTBXUAF3reX69U7NLn server | WEKpx9p55eSu1mnQfiPCAQJoxPX8TbQiMfue78mlljNSIuLvRA3zuh3dINnvq server | hZKiWiOO1gk0qKB1DUtXlegYIWH1Qi9CA07Fnk8TrJhHRuBWtEPHZBKr5IQau server | tM4Xib6b2yZOZKN4zSF08eG21XJkOgTIz7kOHVjNUwwd9wDXZEWOWIzppAsi3 server | 06fnVxT0imh4h" server | DNS name: "ovKsdQaq9Ac42lplB9XaIjTWiTsSECYqLzXaFA33gc197lwBnn9WdM server | RBVtsS3tYVTa5dbkNyfUYeFqdOXp6lZfBbWU3jyMkR6vmyt3GgwxN1VY1Wj2z server | NaXWkqi8tdoLaAUai0hENX0ltGYVm1XM4sLjJVEMURA6K5E1ztGIALDWrmRuk server | OGjT0LJWVxMh3G8fVYAtUJKPiX2b6EJfQtwGe6yzu6k6PEdRNacPLS60NZjpf server | J3vehIGvBE4lq" server | DNS name: "RB2PZwDwtJUC2RZmkdgyMNAqk3E43DoqaE0jBc3OHRpYCGyFaBO2OW server | Zy1gpHLWULVyJ0BQ4FZO3Il2OUqNaXMSvqaiMUY4Wihbo6ggZW1PVtOQrXnjZ server | QHrFK2AePjUmaqKTSbQpZI70bCDl0laf3ANuT58HdaLUVoe00SshLCrGu0TCg server | teWEyBLJiU8xdYqX7q7jd9kXqgNODelh1u3srma604JNRCFE3kTEuHQeWtdv3 server | cHNZVrgcVPvXF" server | DNS name: "6SZIKa0X2SNvvtM0Ns1y1pXzRP5U9Z0a6s5RpE8jpiHQLy2axpA8Du server | fGfR7MvoRQVpLUTo06kbMuX5IgCwrV4x8oZFUdibFOnLBxcAPoSzAKt8mXfmw server | 1hTxGUCH8BMI7aMi5bnvw7zvo8wt3mvgM1KoJfQFXvsxpxqForGhUOZgwRTlb server | XZvJrBk8pWfEmkxJNiJr9eqzyAIpNBYxr8TmvWfKrn62amHqNCiK8kFeN30c0 server | f2cAai86E3oY6" server | server | Name: Certificate Subject Key ID server | Data: server | 07:41:d2:71:d1:b4:5b:a6:5e:76:81:f3:54:4a:52:e3: server | 22:0e:07:89 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 89:79:5e:78:70:34:4f:18:a9:85:86:d6:41:ea:a6:f6: server | 5a:f1:59:b0 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:46:02:21:00:bd:4a:cb:db:57:95:73:0a:23:04:5f: server | 98:f4:8c:54:35:81:29:96:06:39:2f:52:02:42:f2:01: server | c5:45:32:a1:db:02:21:00:cd:07:47:8d:80:d4:30:68: server | fe:53:de:e7:c7:e8:70:cd:6c:5f:58:06:48:81:7c:a7: server | 25:9d:37:f6:2b:fe:b6:8a server | Fingerprint (SHA-256): server | D7:66:E9:1A:E5:D6:17:DC:D8:90:86:0F:F8:48:B4:B1:CB:AD:7B:A4:1A:14:87:80:33:01:8B:83:2F:14:15:D4 server | Fingerprint (SHA1): server | 6E:18:E0:74:CC:C3:C0:D3:D3:A3:C8:1F:EC:E4:5F:EF:D6:76:D1:63 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.003 DEBUG Default socket send buffer size is Ok(212992) server | 0.003 DEBUG Default socket receive buffer size is 1048576, not changing server | 0.003 INFO Server waiting for connection on: Ok([::]:443) server | 0.276 DEBUG [Server] Unsupported version: 57414954 server | 0.276 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:38279 Tos(Cs0, NotEct) len 15 sim | server:443 is available after 501.195994ms 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)tcpdump: , snapshot length 262144 bytes sim | listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | ./demo_client -l d -L /logs/client.log -D /downloads -k /logs/keys.log -K 30 -o -U "https://server4:443/huwbglvzal" client | client | option log level :d client | option log directory :/logs/client.log client | option save body dir: /downloads client | key output file: /logs/keys.log client | client life circle time: 30 client | set interop mode client | option url only:https://server4:443/huwbglvzal client | server[server4] addr: 193.167.100.100:443. client | No.0 path created id = 0 client | open file[/downloads/huwbglvzal] suc server | 0.991 DEBUG [Server] Handle initial server | 0.991 INFO AddressValidation: no token; accepting server | 0.991 INFO [Server] Accept connection CID [8]: 0f6c9f21f0ffa050 server | 0.991 DEBUG Overwrite initial version Version1 ==> Version1 server | 0.995 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 0.995 INFO [Server ...] Received valid Initial packet with scid CID [8]: 2ca767221c399c29 dcid CID [8]: 0f6c9f21f0ffa050 server | 0.995 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=0f6c9f21f0ffa050 server | 0.995 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=7 server | 0.995 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 0.995 DEBUG [Server 0f6c9f21f0ffa050] State change from Init -> WaitInitial server | 0.995 DEBUG [Server 0f6c9f21f0ffa050] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1200 server | -> RX Crypto { offset: 0, len: 305 } server | -> RX Padding { len: 845 } server | 0.995 DEBUG 0-RTT: no token, no 0-RTT server | 0.996 DEBUG Read secret available for Handshake: SymKey [32]: ac0cc4096fd8cd85d72d9ef80b851b7028218870e82a8224b671fee111ca91e3 server | 0.996 DEBUG Write secret available for Handshake: SymKey [32]: bbca23116ea87e7d04940819f699450c046d116cb42376c9cfce5860f41aa091 server | 0.996 DEBUG Writing transport parameters, msg=8 server | 0.996 DEBUG Read secret available for ApplicationData: SymKey [32]: 24e6c72bf4f8acecbec8d5869218d29992d18561ac3f66610263abb9e313bb2a server | 0.996 DEBUG Write secret available for ApplicationData: SymKey [32]: cd4fcbbd71cee0fc8fb7c7f24fdccd0b9c355672e8866152830878d0980b039e server | 0.996 DEBUG [Agent 0x55d95d6b6650] state -> InProgress server | 0.996 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 0.996 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 0.996 DEBUG [Crypto] Handshake keys installed server | 0.996 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 0.997 DEBUG [Crypto] Application write key installed server | 0.997 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 470, tv_nsec: 669865089 } server | 0.997 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:38122] Make permanent server | 0.997 DEBUG [unv-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] set as primary path server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] State change from WaitInitial -> Handshaking server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.997 DEBUG Building Initial dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG CRYPTO for in offset=0, len=123 server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] pn=7 type=Initial pri-unv-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 177 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 123 } server | 0.997 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG CRYPTO for hs offset=0, len=1007 server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] pn=0 type=Handshake pri-unv-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 0, len: 1007 } server | 0.997 DEBUG packet_sent this=0x55d95d6b66d0, pn=0, ps=1055 server | 0.997 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 0.997 DEBUG packet_sent this=0x55d95d6b66d0, pn=7, ps=177 server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.997 DEBUG Building Initial dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG CRYPTO for hs offset=1007, len=1183 server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] pn=1 type=Handshake pri-unv-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 1007, len: 1183 } server | 0.997 DEBUG packet_sent this=0x55d95d6b66d0, pn=1, ps=1232 server | 0.997 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 0.997 DEBUG Building Initial dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG CRYPTO for hs offset=2190, len=1087 server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] pn=2 type=Handshake pri-unv-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1136 server | TX -> Crypto { offset: 2190, len: 1087 } server | 0.997 DEBUG packet_sent this=0x55d95d6b66d0, pn=2, ps=1136 server | 0.997 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.997 DEBUG Building Initial dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 0.997 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] delay duration 29.994259237s server | 0.997 DEBUG Setting timeout of 29.994259237s server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.997 DEBUG Building Initial dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 0.997 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 0.997 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 0.997 DEBUG [Server 0f6c9f21f0ffa050] delay duration 29.994216627s server | 0.997 DEBUG Setting timeout of 29.994216627s sim | Dropping packet 2 (1200 bytes) from 193.167.0.100 sim | Dropping packet 3 (53 bytes) from 193.167.0.100 sim | Dropping packet 4 (52 bytes) from 193.167.0.100 sim | Dropping packet 5 (52 bytes) from 193.167.0.100 sim | Dropping packet 6 (48 bytes) from 193.167.0.100 sim | Dropping packet 7 (48 bytes) from 193.167.0.100 server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] Drop packet number space in server | 1.418 DEBUG [recovery::Loss] Reset loss recovery state for Initial server | 1.418 INFO [pri-unv-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] discarding a packet without an RTT estimate; guessing RTT=426.974193ms server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] pn=5 type=Handshake pri-unv-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 48 server | -> RX Ping server | 1.418 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 471, tv_nsec: 96839282 } server | 1.418 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 471, tv_nsec: 96839282 } server | 1.418 DEBUG [pri-unv-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 96839282 } server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.418 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.418 DEBUG CRYPTO for hs offset=0, len=1179 server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] pn=3 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) 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: 1179 } server | 1.418 DEBUG packet_sent this=0x55d95d6b66d0, pn=3, ps=1232 server | 1.418 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.418 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.418 DEBUG CRYPTO for hs offset=1179, len=1183 server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] pn=4 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 1179, len: 1183 } server | 1.418 DEBUG packet_sent this=0x55d95d6b66d0, pn=4, ps=1232 server | 1.418 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.418 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.418 DEBUG CRYPTO for hs offset=2362, len=1183 server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] pn=5 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 2362, len: 1183 } server | 1.418 DEBUG packet_sent this=0x55d95d6b66d0, pn=5, ps=1232 server | 1.418 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.418 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.418 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.418 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.418 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.419 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.419 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.419 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.419 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.419 DEBUG [Server 0f6c9f21f0ffa050] delay duration 20.859232ms server | 1.419 DEBUG Setting timeout of 20.859232ms server | 1.419 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.419 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.419 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.419 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.419 DEBUG [Server 0f6c9f21f0ffa050] delay duration 20.820409ms server | 1.419 DEBUG Setting timeout of 20.820409ms server | 1.441 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.441 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.441 DEBUG CRYPTO for hs offset=3545, len=1183 server | 1.441 DEBUG [Server 0f6c9f21f0ffa050] pn=6 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 3545, len: 1183 } server | 1.441 DEBUG packet_sent this=0x55d95d6b66d0, pn=6, ps=1232 server | 1.441 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.441 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.441 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.441 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.441 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.441 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.441 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.441 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.441 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.441 DEBUG [Server 0f6c9f21f0ffa050] delay duration 19.932099ms server | 1.441 DEBUG Setting timeout of 19.932099ms server | 1.441 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.441 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.441 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.441 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.441 DEBUG [Server 0f6c9f21f0ffa050] delay duration 19.90084ms server | 1.441 DEBUG Setting timeout of 19.90084ms server | 1.450 DEBUG [Server 0f6c9f21f0ffa050] pn=6 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 40, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 1.450 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=hs, ranges=[0..=3] server | 1.450 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=3 server | 1.450 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3696/16975 ssthresh 18446744073709551615] slow start += 4655 server | 1.450 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3696/16975 ssthresh 18446744073709551615] on_packets_acked this=0x55d95d6b66d0, limited=0, bytes_in_flight=3696, cwnd=16975, state=SlowStart, new_acked=4655 server | 1.450 DEBUG Acked crypto frame space=hs offset=0 length=1179 server | 1.450 DEBUG Acked crypto frame space=hs offset=2190 length=1087 server | 1.450 DEBUG Acked crypto frame space=hs offset=1007 length=1183 server | 1.450 DEBUG Acked crypto frame space=hs offset=0 length=1007 server | 1.450 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 129111796 } server | 1.450 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.450 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.450 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.450 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.450 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.241646ms server | 1.450 DEBUG Setting timeout of 3.241646ms server | 1.450 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.451 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.451 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.451 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.451 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.08381ms server | 1.451 DEBUG Setting timeout of 3.08381ms server | 1.451 DEBUG [Server 0f6c9f21f0ffa050] pn=7 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 16, first_ack_range: 4, ack_ranges: [], ecn_count: None } server | 1.451 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=hs, ranges=[0..=4] server | 1.451 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=4 server | 1.451 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2464/18207 ssthresh 18446744073709551615] slow start += 1232 server | 1.451 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2464/18207 ssthresh 18446744073709551615] on_packets_acked this=0x55d95d6b66d0, limited=0, bytes_in_flight=2464, cwnd=18207, state=SlowStart, new_acked=1232 server | 1.451 DEBUG Acked crypto frame space=hs offset=1179 length=1183 server | 1.451 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 129836904 } server | 1.451 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.451 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.451 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.451 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.451 DEBUG [Server 0f6c9f21f0ffa050] delay duration 274.453µs server | 1.451 DEBUG Setting timeout of 274.453µs server | 1.451 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.451 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.451 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.451 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.451 DEBUG [Server 0f6c9f21f0ffa050] delay duration 151.543µs server | 1.451 DEBUG Setting timeout of 151.543µs server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] pn=8 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 18, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=hs, ranges=[0..=5] server | 1.452 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=5 server | 1.452 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1232/19439 ssthresh 18446744073709551615] slow start += 1232 server | 1.452 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1232/19439 ssthresh 18446744073709551615] on_packets_acked this=0x55d95d6b66d0, limited=0, bytes_in_flight=1232, cwnd=19439, state=SlowStart, new_acked=1232 server | 1.452 DEBUG Acked crypto frame space=hs offset=2362 length=1183 server | 1.452 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 130887121 } server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.452 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.452 DEBUG CRYPTO for hs offset=4728, len=1183 server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] pn=7 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 4728, len: 1183 } server | 1.452 DEBUG packet_sent this=0x55d95d6b66d0, pn=7, ps=1232 server | 1.452 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.452 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.452 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.452 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.452 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.452 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.452 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] delay duration 6.554539ms server | 1.452 DEBUG Setting timeout of 6.554539ms server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.452 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.452 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.452 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.452 DEBUG [Server 0f6c9f21f0ffa050] delay duration 6.508983ms server | 1.452 DEBUG Setting timeout of 6.508983ms server | 1.460 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.460 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.460 DEBUG CRYPTO for hs offset=5911, len=1183 server | 1.460 DEBUG [Server 0f6c9f21f0ffa050] pn=8 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 5911, len: 1183 } server | 1.460 DEBUG packet_sent this=0x55d95d6b66d0, pn=8, ps=1232 server | 1.460 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.460 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.460 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.460 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.460 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.460 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.460 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.460 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.460 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.460 DEBUG [Server 0f6c9f21f0ffa050] delay duration 8.696417ms server | 1.460 DEBUG Setting timeout of 8.696417ms server | 1.460 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.460 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.460 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.460 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.460 DEBUG [Server 0f6c9f21f0ffa050] delay duration 8.663315ms server | 1.460 DEBUG Setting timeout of 8.663315ms server | 1.470 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.470 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.470 DEBUG CRYPTO for hs offset=7094, len=1183 server | 1.470 DEBUG [Server 0f6c9f21f0ffa050] pn=9 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 7094, len: 1183 } server | 1.470 DEBUG packet_sent this=0x55d95d6b66d0, pn=9, ps=1232 server | 1.470 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.470 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.470 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.470 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.470 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.470 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.470 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.470 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.470 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.470 DEBUG [Server 0f6c9f21f0ffa050] delay duration 7.871203ms server | 1.470 DEBUG Setting timeout of 7.871203ms server | 1.470 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.470 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.470 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.470 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.470 DEBUG [Server 0f6c9f21f0ffa050] delay duration 7.840987ms server | 1.470 DEBUG Setting timeout of 7.840987ms server | 1.472 DEBUG [Server 0f6c9f21f0ffa050] pn=9 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 23, first_ack_range: 6, ack_ranges: [], ecn_count: None } server | 1.473 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=hs, ranges=[0..=6] server | 1.473 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=6 server | 1.473 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3696/20671 ssthresh 18446744073709551615] slow start += 1232 server | 1.473 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3696/20671 ssthresh 18446744073709551615] on_packets_acked this=0x55d95d6b66d0, limited=0, bytes_in_flight=3696, cwnd=20671, state=SlowStart, new_acked=1232 server | 1.473 DEBUG Acked crypto frame space=hs offset=3545 length=1183 server | 1.473 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 151244280 } server | 1.473 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.473 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.473 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.473 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.473 DEBUG [Server 0f6c9f21f0ffa050] delay duration 4.006738ms server | 1.473 DEBUG Setting timeout of 4.006738ms server | 1.473 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.473 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.473 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.473 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.473 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.918102ms server | 1.473 DEBUG Setting timeout of 3.918102ms server | 1.478 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.478 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.478 DEBUG CRYPTO for hs offset=8277, len=1183 server | 1.478 DEBUG [Server 0f6c9f21f0ffa050] pn=10 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1232 server | TX -> Crypto { offset: 8277, len: 1183 } server | 1.478 DEBUG packet_sent this=0x55d95d6b66d0, pn=10, ps=1232 server | 1.478 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.478 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.478 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.478 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.478 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.478 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.478 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.478 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.478 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.478 DEBUG [Server 0f6c9f21f0ffa050] delay duration 6.594985ms server | 1.478 DEBUG Setting timeout of 6.594985ms server | 1.478 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.478 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.478 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.478 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.478 DEBUG [Server 0f6c9f21f0ffa050] delay duration 6.564007ms server | 1.478 DEBUG Setting timeout of 6.564007ms server | 1.484 DEBUG [Server 0f6c9f21f0ffa050] pn=10 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 20, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | 1.484 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=hs, ranges=[0..=7] server | 1.484 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=7 server | 1.484 DEBUG on_packets_acked this=0x55d95d6b66d0, limited=1, bytes_in_flight=3696, cwnd=20671, state=SlowStart, new_acked=1232 server | 1.484 DEBUG Acked crypto frame space=hs offset=4728 length=1183 server | 1.484 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 162626534 } server | 1.484 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.484 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.484 DEBUG CRYPTO for hs offset=9460, len=512 server | 1.484 DEBUG [Server 0f6c9f21f0ffa050] pn=11 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 561 server | TX -> Crypto { offset: 9460, len: 512 } server | 1.484 DEBUG packet_sent this=0x55d95d6b66d0, pn=11, ps=561 server | 1.484 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.484 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.484 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.484 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.484 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.484 DEBUG [Server 0f6c9f21f0ffa050] delay duration 2.770984ms server | 1.484 DEBUG Setting timeout of 2.770984ms server | 1.484 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.484 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.484 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.484 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.484 DEBUG [Server 0f6c9f21f0ffa050] delay duration 2.727392ms server | 1.484 DEBUG Setting timeout of 2.727392ms server | 1.488 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.488 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.488 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.488 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.488 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.303537283s server | 1.488 DEBUG Setting timeout of 1.303537283s server | 1.488 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.488 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.488 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.488 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.488 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.303511094s server | 1.488 DEBUG Setting timeout of 1.303511094s server | 1.491 DEBUG [Server 0f6c9f21f0ffa050] pn=11 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 19, first_ack_range: 8, ack_ranges: [], ecn_count: None } server | 1.491 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=hs, ranges=[0..=8] server | 1.491 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=8 server | 1.491 DEBUG on_packets_acked this=0x55d95d6b66d0, limited=1, bytes_in_flight=3025, cwnd=20671, state=SlowStart, new_acked=1232 server | 1.491 DEBUG Acked crypto frame space=hs offset=5911 length=1183 server | 1.491 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 170051819 } server | 1.491 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.491 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.491 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.491 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.491 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.20986002s server | 1.491 DEBUG Setting timeout of 1.20986002s server | 1.491 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.491 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.491 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.491 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.491 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.209796622s server | 1.491 DEBUG Setting timeout of 1.209796622s server | 1.502 DEBUG [Server 0f6c9f21f0ffa050] pn=12 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 23, first_ack_range: 9, ack_ranges: [], ecn_count: None } server | 1.502 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=hs, ranges=[0..=9] server | 1.502 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=9 server | 1.502 DEBUG on_packets_acked this=0x55d95d6b66d0, limited=1, bytes_in_flight=1793, cwnd=20671, state=SlowStart, new_acked=1232 server | 1.502 DEBUG Acked crypto frame space=hs offset=7094 length=1183 server | 1.502 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 180306079 } server | 1.502 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.502 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.502 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.502 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.502 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.103122456s server | 1.502 DEBUG Setting timeout of 1.103122456s server | 1.502 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.502 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.502 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.502 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.502 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.103015696s server | 1.502 DEBUG Setting timeout of 1.103015696s server | 1.509 DEBUG [Server 0f6c9f21f0ffa050] pn=13 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 10, ack_delay: 23, first_ack_range: 10, ack_ranges: [], ecn_count: None } server | 1.509 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=hs, ranges=[0..=10] server | 1.509 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=10 server | 1.509 DEBUG on_packets_acked this=0x55d95d6b66d0, limited=1, bytes_in_flight=561, cwnd=20671, state=SlowStart, new_acked=1232 server | 1.509 DEBUG Acked crypto frame space=hs offset=8277 length=1183 server | 1.509 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 188099083 } server | 1.509 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.509 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.509 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.509 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.509 DEBUG [Server 0f6c9f21f0ffa050] delay duration 998.032834ms server | 1.509 DEBUG Setting timeout of 998.032834ms server | 1.509 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.509 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.509 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.509 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.509 DEBUG [Server 0f6c9f21f0ffa050] delay duration 997.887783ms server | 1.509 DEBUG Setting timeout of 997.887783ms server | 1.520 DEBUG [Server 0f6c9f21f0ffa050] pn=14 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 86 server | -> RX Crypto { offset: 0, len: 36 } server | 1.520 DEBUG [Agent 0x55d95d6b6650] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 23, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 1.520 DEBUG [Server 0f6c9f21f0ffa050] TLS connection complete server | 1.520 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 198567807 } server | 1.520 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.520 DEBUG [Crypto] application read keys installed server | 1.520 DEBUG [Server 0f6c9f21f0ffa050] State change from Handshaking -> Connected server | 1.520 DEBUG [Server 0f6c9f21f0ffa050] State change from Connected -> Confirmed server | 1.520 DEBUG PMTUD started with probe size 1380 server | 1.520 INFO [Server 0f6c9f21f0ffa050] Connection established server | 1.520 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 471, tv_nsec: 198567807 } server | 1.520 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.520 DEBUG Building Handshake dcid Some(CID [8]: 2ca767221c399c29) scid Some(CID [10]: 619dcfd4970b793c333d) server | 1.520 DEBUG [Server 0f6c9f21f0ffa050] pn=12 type=Handshake pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 49 server | TX -> Ack { largest_acknowledged: 14, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: None } server | 1.520 DEBUG [Server 0f6c9f21f0ffa050] Drop packet number space hs server | 1.520 DEBUG [recovery::Loss] Reset loss recovery state for Handshake server | 1.520 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.520 DEBUG [Server 0f6c9f21f0ffa050] pn=0 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [16, 148, 245, 49, 18, 149, 64, 124, 181, 43], stateless_reset_token: [200, 172, 119, 45, 113, 234, 129, 135, 163, 221, 116, 104, 65, 4, 9, 117] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [190, 229, 95, 85, 136, 85, 39, 16, 204, 66], stateless_reset_token: [90, 220, 14, 187, 35, 25, 208, 252, 15, 87, 14, 51, 213, 56, 116, 115] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [236, 184, 145, 108, 64, 197, 186, 161, 127, 24], stateless_reset_token: [123, 138, 15, 41, 160, 176, 143, 131, 4, 183, 177, 88, 191, 216, 200, 162] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [70, 25, 198, 106, 65, 188, 46, 233, 242, 126], stateless_reset_token: [120, 100, 54, 77, 21, 137, 31, 40, 235, 255, 92, 89, 50, 121, 22, 44] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [238, 234, 90, 27, 119, 9, 183, 254, 203, 156], stateless_reset_token: [66, 87, 57, 128, 81, 71, 114, 115, 99, 71, 95, 209, 99, 190, 168, 27] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [206, 163, 91, 29, 104, 176, 117, 166, 50, 58], stateless_reset_token: [212, 197, 165, 67, 139, 254, 186, 26, 143, 125, 62, 88, 183, 60, 254, 106] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [65, 216, 244, 85, 68, 232, 64, 137, 18, 236], stateless_reset_token: [141, 102, 43, 56, 126, 104, 138, 253, 32, 200, 42, 188, 222, 180, 80, 129] } server | TX -> Padding { len: 1046 } server | 1.520 DEBUG packet_sent this=0x55d95d6b66d0, pn=0, ps=1283 server | 1.520 DEBUG ECN probing: sent 1 probes server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.521 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.521 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] pn=1 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 1.521 DEBUG packet_sent this=0x55d95d6b66d0, pn=1, ps=1332 server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.521 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.521 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.521 DEBUG ECN probing: sent 2 probes server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.521 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.521 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] delay duration 4.784317ms server | 1.521 DEBUG Setting timeout of 4.784317ms server | 1.521 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 1 server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.521 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.521 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] delay duration 4.752387ms server | 1.521 DEBUG Setting timeout of 4.752387ms server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] pn=0 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 52 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 1.521 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 471, tv_nsec: 219461861 } server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.521 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.521 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.521 DEBUG [Server 0f6c9f21f0ffa050] delay duration 4.719906ms server | 1.521 DEBUG Setting timeout of 4.719906ms server | 1.522 DEBUG [Server 0f6c9f21f0ffa050] send session ticket 406400080f6c9f21f0ffa0500104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a619dcfd4970b793c333d110c000000010aaaeafa000000016ab200c0000000ff02de1a0243e8200244b0686921 server | 1.522 DEBUG Path = 'huwbglvzal' server | 1.522 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.522 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.522 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.522 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.530549ms server | 1.522 DEBUG Setting timeout of 3.530549ms server | 1.527 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.527 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.527 DEBUG [Server 0f6c9f21f0ffa050] pn=2 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 1.527 DEBUG packet_sent this=0x55d95d6b66d0, pn=2, ps=1232 server | 1.527 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.527 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.527 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.527 DEBUG ECN probing: sent 3 probes server | 1.527 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.527 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.527 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.527 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.327164ms server | 1.527 DEBUG Setting timeout of 3.327164ms server | 1.527 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.527 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.527 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.527 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.30354ms server | 1.527 DEBUG Setting timeout of 3.30354ms server | 1.532 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.532 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.532 DEBUG [Server 0f6c9f21f0ffa050] pn=3 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 1.532 DEBUG packet_sent this=0x55d95d6b66d0, pn=3, ps=1232 server | 1.532 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.532 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.532 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.532 DEBUG ECN probing: sent 4 probes server | 1.532 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.532 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.532 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.532 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.091994ms server | 1.532 DEBUG Setting timeout of 3.091994ms server | 1.532 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.532 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.532 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.532 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.069632ms server | 1.532 DEBUG Setting timeout of 3.069632ms server | 1.537 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.537 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.537 DEBUG [Server 0f6c9f21f0ffa050] pn=4 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false } server | 1.537 DEBUG packet_sent this=0x55d95d6b66d0, pn=4, ps=1232 server | 1.537 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.537 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.537 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.537 DEBUG ECN probing: sent 5 probes server | 1.537 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.537 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.537 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.537 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.781329ms server | 1.537 DEBUG Setting timeout of 3.781329ms server | 1.537 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.537 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.537 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.537 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.747716ms server | 1.537 DEBUG Setting timeout of 3.747716ms server | 1.542 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.542 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.542 DEBUG [Server 0f6c9f21f0ffa050] pn=5 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 1232 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 2633, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Stream { stream_id: 0, offset: 3608, len: >>1196, fin: false } server | 1.542 DEBUG packet_sent this=0x55d95d6b66d0, pn=5, ps=1232 server | 1.542 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.542 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.542 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.542 DEBUG ECN probing: sent 6 probes server | 1.542 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.542 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.542 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.542 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.504355ms server | 1.542 DEBUG Setting timeout of 3.504355ms server | 1.542 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.542 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.542 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.542 DEBUG [Server 0f6c9f21f0ffa050] delay duration 3.472154ms server | 1.542 DEBUG Setting timeout of 3.472154ms server | 1.547 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.547 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.547 DEBUG CRYPTO for ap offset=0, len=305 server | 1.547 DEBUG [Server 0f6c9f21f0ffa050] pn=6 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 702 server | TX -> Stream { stream_id: 0, offset: 4804, len: 316, fin: true } server | TX -> Crypto { offset: 0, len: 305 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 159, 165, 102, 43, 105, 66, 126, 16, 249, 61, 212, 183, 242, 146, 239, 6, 174, 161, 220, 202, 147, 228, 95, 21, 34, 128, 163, 129, 96, 185, 147, 92, 47, 104, 18, 56] } server | 1.547 DEBUG packet_sent this=0x55d95d6b66d0, pn=6, ps=702 server | 1.547 DEBUG ECN probing: sent 7 probes server | 1.547 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.547 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.547 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.547 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.106507ms server | 1.547 DEBUG Setting timeout of 1.106507ms server | 1.547 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.547 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.547 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.547 DEBUG [Server 0f6c9f21f0ffa050] delay duration 916.06µs server | 1.547 DEBUG Setting timeout of 916.06µs server | 1.548 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.548 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.548 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.548 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.047137457s server | 1.548 DEBUG Setting timeout of 1.047137457s server | 1.548 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.548 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.548 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.548 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.047106309s server | 1.548 DEBUG Setting timeout of 1.047106309s server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] pn=1 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 59 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [52, 120, 94, 14, 141, 113, 254, 251], stateless_reset_token: [29, 224, 164, 206, 197, 207, 192, 141, 50, 24, 171, 6, 71, 195, 125, 46] } server | 1.553 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 471, tv_nsec: 251409167 } server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.553 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] delay duration 20ms server | 1.553 DEBUG Setting timeout of 20ms server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] pn=2 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 59 server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [61, 1, 4, 53, 240, 7, 210, 96], stateless_reset_token: [233, 43, 179, 224, 89, 206, 202, 80, 90, 54, 158, 124, 125, 25, 224, 48] } server | 1.553 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 471, tv_nsec: 231481453 } server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] pn=7 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 31 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | 1.553 DEBUG ECN probing: sent 8 probes server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.553 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.042743789s server | 1.553 DEBUG Setting timeout of 1.042743789s server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.553 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] delay duration 1.042721067s server | 1.553 DEBUG Setting timeout of 1.042721067s server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] pn=3 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 14, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=ap, ranges=[0..=1] server | 1.553 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=1 server | 1.553 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5630/23286 ssthresh 18446744073709551615] slow start += 2615 server | 1.553 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5630/23286 ssthresh 18446744073709551615] on_packets_acked this=0x55d95d6b66d0, limited=0, bytes_in_flight=5630, cwnd=23286, state=SlowStart, new_acked=2615 server | 1.553 DEBUG PMTUD probe of size 1380 succeeded server | 1.553 DEBUG PMTUD started with probe size 1420 server | 1.553 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.553 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] pn=8 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1345 } server | 1.553 DEBUG packet_sent this=0x55d95d6b66d0, pn=8, ps=1372 server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.553 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG ECN probing: sent 9 probes server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.553 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] delay duration 952.690416ms server | 1.553 DEBUG Setting timeout of 952.690416ms server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.553 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.553 DEBUG [Server 0f6c9f21f0ffa050] delay duration 952.659298ms server | 1.553 DEBUG Setting timeout of 952.659298ms client | >>>>>>>> request time cost:593348 us, speed:8 K/s client | >>>>>>>> user_stream[0x55a7745112f0], req: /huwbglvzal, send_body_size:11, recv_body_size:5120 server | 1.564 DEBUG [Server 0f6c9f21f0ffa050] pn=4 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 31, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 1.564 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=ap, ranges=[0..=3] server | 1.564 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=3 server | 1.564 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4538/25750 ssthresh 18446744073709551615] slow start += 2464 server | 1.564 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4538/25750 ssthresh 18446744073709551615] on_packets_acked this=0x55d95d6b66d0, limited=0, bytes_in_flight=4538, cwnd=25750, state=SlowStart, new_acked=2464 server | 1.564 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.564 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.564 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.564 DEBUG [Server 0f6c9f21f0ffa050] delay duration 852.028636ms server | 1.564 DEBUG Setting timeout of 852.028636ms server | 1.564 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.564 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.564 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.564 DEBUG [Server 0f6c9f21f0ffa050] delay duration 851.96631ms server | 1.564 DEBUG Setting timeout of 851.96631ms server | 1.574 DEBUG [Server 0f6c9f21f0ffa050] pn=5 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 44, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 1.574 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=ap, ranges=[0..=5] server | 1.574 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=5 server | 1.574 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2074/28214 ssthresh 18446744073709551615] slow start += 2464 server | 1.574 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2074/28214 ssthresh 18446744073709551615] on_packets_acked this=0x55d95d6b66d0, limited=0, bytes_in_flight=2074, cwnd=28214, state=SlowStart, new_acked=2464 server | 1.574 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.574 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.574 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.574 DEBUG [Server 0f6c9f21f0ffa050] delay duration 758.014289ms server | 1.574 DEBUG Setting timeout of 758.014289ms server | 1.574 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.574 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.574 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.574 DEBUG [Server 0f6c9f21f0ffa050] delay duration 757.874597ms server | 1.574 DEBUG Setting timeout of 757.874597ms server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] pn=10 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 14, first_ack_range: 8, ack_ranges: [], ecn_count: None } server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=ap, ranges=[0..=8] server | 1.585 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=8 server | 1.585 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/30288 ssthresh 18446744073709551615] slow start += 2074 server | 1.585 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/30288 ssthresh 18446744073709551615] on_packets_acked this=0x55d95d6b66d0, limited=0, bytes_in_flight=0, cwnd=30288, state=SlowStart, new_acked=2074 server | 1.585 DEBUG PMTUD probe of size 1420 succeeded server | 1.585 DEBUG PMTUD started with probe size 1470 server | 1.585 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 1.585 DEBUG Acked crypto frame space=ap offset=0 length=305 server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.585 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.585 DEBUG Sending PMTUD probe of size 1470, count 1 server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] pn=9 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, Ect0) len 1422 server | TX -> Ping server | TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 } server | TX -> Padding { len: 1393 } server | 1.585 DEBUG packet_sent this=0x55d95d6b66d0, pn=9, ps=1422 server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.585 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.585 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.585 DEBUG ECN probing: sent 10 probes server | 1.585 DEBUG ECN probing concluded with 10 probes sent server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.585 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.585 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] delay duration 700.403647ms server | 1.585 DEBUG Setting timeout of 700.403647ms server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.585 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.585 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.585 DEBUG [Server 0f6c9f21f0ffa050] delay duration 700.278683ms server | 1.585 DEBUG Setting timeout of 700.278683ms server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] pn=11 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 3226, first_ack_range: 9, ack_ranges: [], ecn_count: None } server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=ap, ranges=[0..=9] server | 1.643 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=9 server | 1.643 WARN ECN validation failed, no ECN counts in ACK frame server | 1.643 DEBUG on_packets_acked this=0x55d95d6b66d0, limited=1, bytes_in_flight=0, cwnd=30288, state=SlowStart, new_acked=1422 server | 1.643 DEBUG PMTUD probe of size 1470 succeeded server | 1.643 DEBUG PMTUD started with probe size 1500 server | 1.643 DEBUG PLPMTU changed from 1372 to 1422, updating pacer server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.643 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.643 DEBUG Sending PMTUD probe of size 1500, count 1 server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] pn=10 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 1452 server | TX -> Ping server | TX -> Padding { len: 1425 } server | 1.643 DEBUG packet_sent this=0x55d95d6b66d0, pn=10, ps=1452 server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.643 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.643 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.643 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.643 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] delay duration 628.361144ms server | 1.643 DEBUG Setting timeout of 628.361144ms server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.643 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.643 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.643 DEBUG [Server 0f6c9f21f0ffa050] delay duration 628.329815ms server | 1.643 DEBUG Setting timeout of 628.329815ms server | 1.701 DEBUG [Server 0f6c9f21f0ffa050] pn=12 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 10, ack_delay: 3221, first_ack_range: 10, ack_ranges: [], ecn_count: None } server | 1.701 DEBUG [Server 0f6c9f21f0ffa050] Rx ACK space=ap, ranges=[0..=10] server | 1.701 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=10 server | 1.701 DEBUG on_packets_acked this=0x55d95d6b66d0, limited=1, bytes_in_flight=0, cwnd=30288, state=SlowStart, new_acked=1452 server | 1.701 DEBUG PMTUD probe of size 1500 succeeded server | 1.701 INFO PMTUD stopped, PLPMTU is now 1500, raise timer Some(Instant { tv_sec: 1071, tv_nsec: 379260084 }) server | 1.701 DEBUG PLPMTU changed from 1422 to 1452, updating pacer server | 1.701 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.701 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.701 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.701 DEBUG [Server 0f6c9f21f0ffa050] delay duration 30s server | 1.701 DEBUG Setting timeout of 30s server | 1.701 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.701 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.701 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.701 DEBUG [Server 0f6c9f21f0ffa050] delay duration 29.999892859s server | 1.701 DEBUG Setting timeout of 29.999892859s client | [HQ-req] send_bytes:17, recv_bytes:5120, path_info:0-1-5-17-5120 client | task[0], fin_cnt: 1, fin_flag: 1 server | 1.945 DEBUG [Server 0f6c9f21f0ffa050] pn=13 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 35 server | -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" } server | 1.945 INFO [Server 0f6c9f21f0ffa050] ConnectionClose received. Error code: Transport(0) frame type 0 reason server | 1.945 DEBUG [Server 0f6c9f21f0ffa050] State change from Confirmed -> Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 473, tv_nsec: 314564009 } } server | 1.945 DEBUG [pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122] Path validated Instant { tv_sec: 471, tv_nsec: 623987875 } server | 1.945 DEBUG [Server 0f6c9f21f0ffa050] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.945 DEBUG Building Short dcid Some(CID [8]: 2ca767221c399c29) server | 1.945 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 471, tv_nsec: 623987875 } server | 1.945 DEBUG [Server 0f6c9f21f0ffa050] pn=11 type=Short pri-path:2ca767221c399c29 [::]:443->[::ffff:193.167.0.100]:38122 Tos(Cs0, NotEct) len 37 server | TX -> Ack { largest_acknowledged: 13, ack_delay: 0, first_ack_range: 3, ack_ranges: [AckRange { gap: 3, range: 5 }], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" } server | 1.945 DEBUG Setting timeout of 1.690407518s server | 1.945 DEBUG Setting timeout of 1.690396888s server | 1.976 DEBUG [Server ...] Dropped received packet: State Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 473, tv_nsec: 314564009 } }; Total: 2 server | 1.976 DEBUG Setting timeout of 1.659785603s server | 1.976 DEBUG Setting timeout of 1.659754986s client | task finished, total task_req_cnt: 1, req_fin_cnt: 1, req_sent_cnt: 0, req_create_cnt: 1 client | all tasks are finished, will break loop and exit client | 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-09-12 05:05:00,565 Using the client's key log file. 2025-09-12 05:05:00,578 2025-09-12 05:05:00,579 Using the client's key log file. 2025-09-12 05:05:00,579 Using the client's key log file. 2025-09-12 05:05:00,591 2025-09-12 05:05:00,591 Using the client's key log file. 2025-09-12 05:05:00,591 Using the client's key log file. 2025-09-12 05:05:01,162 Check of downloaded files succeeded. 2025-09-12 05:05:01,446 Server sent 9972 bytes in Handshake CRYPTO frames. 2025-09-12 05:05:01,799 Received a 1200 byte Initial packet from the client. Amplification limit: 3600 2025-09-12 05:05:01,799 Received a 1232 byte Handshake packet from the server. Total: 1232 2025-09-12 05:05:01,800 Received a 1232 byte Handshake packet from the server. Total: 2464 2025-09-12 05:05:01,800 Received a 1136 byte Handshake packet from the server. Total: 3600