2025-09-11 19:38:09,629 Generated random file: rabcqnzrfq of size: 1024 2025-09-11 19:38:09,629 Requests: https://server4:443/rabcqnzrfq 2025-09-11 19:38:09,685 2025-09-11 19:38:09,685 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_4g53vcpy/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_5xbw1esf/ DOWNLOADS=/tmp/download_n4xgt8gj/ SERVER_LOGS=/tmp/logs_server_toz6sqsb CLIENT_LOGS=/tmp/logs_client_tg9c_1_5 SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/alibaba/xquic/xquic-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/rabcqnzrfq" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-11 19:38:44,541 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container server Recreated Container client 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 handshake ']' server | + '[' -n /logs/qlog/ ']' server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert server | ++ mktemp server | + P12CERT=/tmp/tmp.E8EtNrgWM4 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.E8EtNrgWM4 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.E8EtNrgWM4 -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 | 6a:67:a0:7b:c9:d6:01:ec:c7:81:bd:cb:47:62:0c:14: server | 79:96:c0:f6 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner Root Certificate Authority" server | Validity: server | Not Before: Thu Sep 11 19:38:09 2025 server | Not After : Sun Sep 21 19:38:09 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:45:a4:ac:6a:75:47:36:dc:e6:40:c5:39:d0:19:c0: server | ed:14:c5:47:c6:ab:4e:3b:9d:49:43:a6:e0:f9:65:15: server | da:76:4c:aa:54:50:41:c6:bd:c6:ad:5f:6a:a8:b3:09: server | 60:77:9d:79:16:12:2c:0a:e9:86:5b:63:04:c5:2c:25: server | d3 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 | server | Name: Certificate Subject Key ID server | Data: server | a3:5f:22:6c:55:5d:86:60:b2:f0:3a:3f:80:55:6f:e0: server | 61:5c:b1:8d server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 31:f9:d8:ad:93:0c:dd:93:61:e2:fc:7a:a6:30:e9:d2: server | eb:08:6e:5c server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:20:63:be:9e:4a:04:41:a1:51:3c:c9:d3:a9: server | 76:a0:ab:b2:e7:3f:5d:1f:50:9e:bf:12:c8:de:59:65: server | 60:c2:83:a1:02:21:00:d0:f7:ad:44:55:a0:b8:52:4c: server | 6d:dd:6b:6f:ff:e3:8f:30:0e:5d:4f:09:a4:0d:f3:d9: server | 96:ee:a6:02:87:dd:f9 server | Fingerprint (SHA-256): server | 09:B1:BB:3A:92:3E:2B:7F:B2:3D:62:57:64:71:95:AE:56:7E:3D:F0:67:60:40:2B:E5:39:B2:CF:FF:CC:9F:FE server | Fingerprint (SHA1): server | 5B:DF:2E:8C:2B:26:3B:9C:09:F1:9A:E6:F5:EF:CA:04:48:BE:70:BB 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 | + '[' handshake = connectionmigration ']' server | + RUST_LOG=debug server | + RUST_BACKTRACE=1 server | + neqo-server --cc cubic --qns-test handshake --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 Ok(212992) server | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing server | 0.002 INFO Server waiting for connection on: Ok([::]:443) server | 0.293 DEBUG [Server] Unsupported version: 57414954 server | 0.293 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:35445 Tos(Cs0, NotEct) len 15 sim | server:443 is available after 501.817518ms sim | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25 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 | ./demo_client -l d -L /logs/client.log -D /downloads -k /logs/keys.log -K 30 -o -U "https://server4:443/rabcqnzrfq" 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/rabcqnzrfq client | server[server4] addr: 193.167.100.100:443. client | No.0 path created id = 0 client | open file[/downloads/rabcqnzrfq] suc server | 1.741 DEBUG [Server] Handle initial server | 1.741 INFO AddressValidation: no token; accepting server | 1.741 INFO [Server] Accept connection CID [8]: 64b40ae3ab8b1dfe server | 1.741 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.743 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.743 INFO [Server ...] Received valid Initial packet with scid CID [8]: 287af5d37f72585c dcid CID [8]: 64b40ae3ab8b1dfe server | 1.743 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=64b40ae3ab8b1dfe server | 1.743 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=627 server | 1.743 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.743 DEBUG [Server 64b40ae3ab8b1dfe] State change from Init -> WaitInitial server | 1.743 DEBUG [Server 64b40ae3ab8b1dfe] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 1200 server | -> RX Crypto { offset: 0, len: 305 } server | -> RX Padding { len: 845 } server | 1.743 DEBUG 0-RTT: no token, no 0-RTT server | 1.744 DEBUG Read secret available for Handshake: SymKey [32]: a9834d117230be0f25cb1eed25ddca8276a6a5475137b50cb1ac577a0c913b87 server | 1.744 DEBUG Write secret available for Handshake: SymKey [32]: a097090319d1402e5e16a03aea474ecf434d0f99df3fc33416fb0e3e5cb4fdb7 server | 1.744 DEBUG Writing transport parameters, msg=8 server | 1.744 DEBUG Read secret available for ApplicationData: SymKey [32]: 805c3d0fcff463725c7a4dca262c8725cb49fce0f717656582996d3a5892ca4b server | 1.744 DEBUG Write secret available for ApplicationData: SymKey [32]: c0f328c2e650616becb3ee917fa36a8b842b1b65805fd1b509349a255b67d136 server | 1.744 DEBUG [Agent 0x561856b95650] state -> InProgress server | 1.744 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.744 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.744 DEBUG [Crypto] Handshake keys installed server | 1.744 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.744 DEBUG [Crypto] Application write key installed server | 1.744 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 304, tv_nsec: 777468679 } server | 1.744 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:42935] Make permanent server | 1.744 DEBUG [unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935] set as primary path server | 1.744 DEBUG [Server 64b40ae3ab8b1dfe] State change from WaitInitial -> Handshaking server | 1.744 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.744 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 1.744 DEBUG CRYPTO for in offset=0, len=123 server | 1.744 DEBUG [Server 64b40ae3ab8b1dfe] pn=627 type=Initial pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 178 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 | 1.744 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 1.744 DEBUG CRYPTO for hs offset=0, len=726 server | 1.744 DEBUG [Server 64b40ae3ab8b1dfe] pn=0 type=Handshake pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 952 server | TX -> Crypto { offset: 0, len: 726 } server | 1.744 DEBUG packet_sent this=0x561856b956d0, pn=0, ps=774 server | 1.744 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 1.744 DEBUG [Server 64b40ae3ab8b1dfe] pad Initial from 952 to PLPMTU 1232 server | 1.744 DEBUG packet_sent this=0x561856b956d0, pn=627, ps=458 server | 1.744 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.744 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 1.745 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 1.745 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 1.745 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.745 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 296.611685ms server | 1.745 DEBUG Setting timeout of 296.611685ms server | 1.745 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.745 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 1.745 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 1.745 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 1.745 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.745 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 296.576479ms server | 1.745 DEBUG Setting timeout of 296.576479ms server | 2.042 DEBUG [recovery::Loss] PTO timer fired for Initial server | 2.042 DEBUG [recovery::Loss] PTO timer fired for Handshake server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] }) server | 2.042 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 304, tv_nsec: 777468679 } server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 123 }) server | 2.042 INFO Lost crypto frame space=in offset=0 length=123 server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 726 }) server | 2.042 INFO Lost crypto frame space=hs offset=0 length=726 server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 2.042 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.042 DEBUG CRYPTO for in offset=0, len=123 server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] pn=628 type=Initial pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 181 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 37605, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 123 } server | 2.042 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.042 DEBUG CRYPTO for hs offset=0, len=726 server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] pn=1 type=Handshake pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 955 server | TX -> Crypto { offset: 0, len: 726 } server | 2.042 DEBUG packet_sent this=0x561856b956d0, pn=1, ps=774 server | 2.042 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] pad Initial from 955 to PLPMTU 1232 server | 2.042 DEBUG packet_sent this=0x561856b956d0, pn=628, ps=458 server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 2.042 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.042 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.042 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.042 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 2.042 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.042 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.042 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.042 DEBUG TX blocked, profile=SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 29.698886457s server | 2.042 DEBUG Setting timeout of 29.698886457s server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 2.042 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.042 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.042 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.042 DEBUG TX blocked, profile=SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 2.042 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 29.69885057s server | 2.042 DEBUG Setting timeout of 29.69885057s server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] pn=1 type=Initial pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 1200 server | -> RX Crypto { offset: 0, len: 305 } server | -> RX Padding { len: 845 } server | 2.487 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 305, tv_nsec: 523533892 } server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.487 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.487 DEBUG CRYPTO for in offset=0, len=123 server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] pn=629 type=Initial pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 178 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 123 } server | 2.487 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.487 DEBUG CRYPTO for hs offset=0, len=726 server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] pn=2 type=Handshake pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 952 server | TX -> Crypto { offset: 0, len: 726 } server | 2.487 DEBUG packet_sent this=0x561856b956d0, pn=2, ps=774 server | 2.487 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] pad Initial from 952 to PLPMTU 1232 server | 2.487 DEBUG packet_sent this=0x561856b956d0, pn=629, ps=458 server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.487 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.487 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.487 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.487 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.487 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.487 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.487 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.487 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 599.799173ms server | 2.487 DEBUG Setting timeout of 599.799173ms server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.487 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.487 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.487 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.487 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.487 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 599.766122ms server | 2.487 DEBUG Setting timeout of 599.766122ms server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] pn=2 type=Initial pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 1200 server | -> RX Crypto { offset: 0, len: 305 } server | -> RX Padding { len: 845 } server | 2.488 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 305, tv_nsec: 524403841 } server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.488 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.488 DEBUG CRYPTO for in offset=0, len=123 server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] pn=630 type=Initial pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 178 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 123 } server | 2.488 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.488 DEBUG CRYPTO for hs offset=0, len=726 server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] pn=3 type=Handshake pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 952 server | TX -> Crypto { offset: 0, len: 726 } server | 2.488 DEBUG packet_sent this=0x561856b956d0, pn=3, ps=774 server | 2.488 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] pad Initial from 952 to PLPMTU 1232 server | 2.488 DEBUG packet_sent this=0x561856b956d0, pn=630, ps=458 server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.488 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.488 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.488 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.488 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.488 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.488 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.488 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.488 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 599.870467ms server | 2.488 DEBUG Setting timeout of 599.870467ms server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.488 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.488 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 2.488 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 2.488 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.488 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 599.838327ms server | 2.488 DEBUG Setting timeout of 599.838327ms server | 3.089 DEBUG [recovery::Loss] PTO timer fired for Initial server | 3.089 DEBUG [recovery::Loss] PTO timer fired for Handshake server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] }) server | 3.089 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 305, tv_nsec: 78311504 } server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 123 }) server | 3.089 INFO Lost crypto frame space=in offset=0 length=123 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1, smallest: 0, ack_needed: true }] }) server | 3.089 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 305, tv_nsec: 523533892 } server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 123 }) server | 3.089 INFO Lost crypto frame space=in offset=0 length=123 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 2, smallest: 0, ack_needed: true }] }) server | 3.089 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 305, tv_nsec: 524403841 } server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 123 }) server | 3.089 INFO Lost crypto frame space=in offset=0 length=123 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 726 }) server | 3.089 INFO Lost crypto frame space=hs offset=0 length=726 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 726 }) server | 3.089 INFO Lost crypto frame space=hs offset=0 length=726 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 726 }) server | 3.089 INFO Lost crypto frame space=hs offset=0 length=726 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 3.089 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.089 DEBUG CRYPTO for in offset=0, len=123 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] pn=631 type=Initial pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 181 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 75108, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 123 } server | 3.089 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.089 DEBUG CRYPTO for hs offset=0, len=726 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] pn=4 type=Handshake pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 955 server | TX -> Crypto { offset: 0, len: 726 } server | 3.089 DEBUG packet_sent this=0x561856b956d0, pn=4, ps=774 server | 3.089 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] pad Initial from 955 to PLPMTU 1232 server | 3.089 DEBUG packet_sent this=0x561856b956d0, pn=631, ps=458 server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 3.089 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.089 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.089 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.089 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.089 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.089 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.089 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.089 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 1.19974942s server | 3.089 DEBUG Setting timeout of 1.19974942s server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.089 DEBUG Building Initial dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.089 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.089 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.089 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.089 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 1.199719855s server | 3.089 DEBUG Setting timeout of 1.199719855s server | 3.248 DEBUG [Server 64b40ae3ab8b1dfe] Drop packet number space in server | 3.248 DEBUG [recovery::Loss] Reset loss recovery state for Initial server | 3.248 INFO [pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935] discarding a packet without an RTT estimate; guessing RTT=1.507067861s server | 3.248 DEBUG [Server 64b40ae3ab8b1dfe] pn=0 type=Handshake pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 86 server | -> RX Crypto { offset: 0, len: 36 } server | 3.248 DEBUG [Agent 0x561856b95650] 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 | 3.248 DEBUG [Server 64b40ae3ab8b1dfe] TLS connection complete server | 3.248 DEBUG [pri-unv-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935] Path validated Instant { tv_sec: 306, tv_nsec: 284536540 } server | 3.248 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 3.248 DEBUG [Crypto] application read keys installed server | 3.248 DEBUG [Server 64b40ae3ab8b1dfe] State change from Handshaking -> Connected server | 3.248 DEBUG [Server 64b40ae3ab8b1dfe] State change from Connected -> Confirmed server | 3.248 DEBUG PMTUD started with probe size 1380 server | 3.248 INFO [Server 64b40ae3ab8b1dfe] Connection established server | 3.249 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 306, tv_nsec: 284536540 } server | 3.249 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.249 DEBUG Building Handshake dcid Some(CID [8]: 287af5d37f72585c) scid Some(CID [10]: 44b7757d16d6dc11f541) server | 3.249 DEBUG [Server 64b40ae3ab8b1dfe] pn=5 type=Handshake pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 49 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.249 DEBUG [Server 64b40ae3ab8b1dfe] Drop packet number space hs server | 3.249 DEBUG [recovery::Loss] Reset loss recovery state for Handshake server | 3.249 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.249 DEBUG [Server 64b40ae3ab8b1dfe] pn=0 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [130, 5, 98, 15, 200, 182, 249, 240, 84, 128], stateless_reset_token: [31, 12, 140, 15, 75, 35, 220, 45, 238, 139, 22, 37, 56, 1, 31, 175] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [210, 72, 167, 135, 237, 23, 17, 67, 227, 116], stateless_reset_token: [11, 136, 80, 226, 12, 150, 46, 193, 254, 244, 86, 94, 178, 245, 46, 35] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [209, 60, 32, 112, 217, 39, 143, 48, 193, 88], stateless_reset_token: [132, 68, 212, 39, 61, 109, 181, 101, 209, 65, 239, 160, 125, 132, 130, 192] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [211, 113, 161, 67, 252, 170, 123, 2, 212, 240], stateless_reset_token: [55, 141, 255, 171, 175, 90, 64, 249, 27, 109, 219, 66, 178, 242, 132, 215] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [222, 70, 50, 98, 150, 12, 212, 77, 179, 113], stateless_reset_token: [175, 153, 244, 80, 225, 175, 70, 53, 143, 124, 73, 130, 141, 210, 177, 8] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [31, 108, 22, 155, 98, 208, 131, 155, 132, 119], stateless_reset_token: [19, 102, 99, 76, 148, 129, 111, 207, 124, 217, 211, 84, 32, 235, 31, 175] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [195, 6, 26, 143, 226, 42, 240, 215, 255, 218], stateless_reset_token: [30, 26, 104, 223, 147, 22, 117, 74, 99, 9, 90, 42, 47, 207, 112, 122] } server | TX -> Padding { len: 1046 } server | 3.249 DEBUG packet_sent this=0x561856b956d0, pn=0, ps=1283 server | 3.249 DEBUG ECN probing: sent 1 probes server | 3.249 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.249 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 2.492502ms server | 3.249 DEBUG Setting timeout of 2.492502ms server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] send session ticket 4064000864b40ae3ab8b1dfe0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a44b7757d16d6dc11f541110c00000001daea5aca000000016ab200c0000000ff02de1a0243e8200244b0686921 server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.250 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 1.417058ms server | 3.250 DEBUG Setting timeout of 1.417058ms server | 3.250 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 1 server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.250 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 1.380199ms server | 3.250 DEBUG Setting timeout of 1.380199ms server | 3.250 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 2 server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.250 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 1.349602ms server | 3.250 DEBUG Setting timeout of 1.349602ms server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] pn=0 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 52 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 3.250 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 306, tv_nsec: 306328155 } server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.250 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 1.327721ms server | 3.250 DEBUG Setting timeout of 1.327721ms server | 3.250 DEBUG Path = 'rabcqnzrfq' server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.250 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.250 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 1.233615ms server | 3.250 DEBUG Setting timeout of 1.233615ms server | 3.252 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.252 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.252 DEBUG Sending PMTUD probe of size 1380, count 1 server | 3.252 DEBUG CRYPTO for ap offset=0, len=273 server | 3.252 DEBUG [Server 64b40ae3ab8b1dfe] pn=1 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true } server | TX -> Crypto { offset: 0, len: 273 } server | 3.252 DEBUG packet_sent this=0x561856b956d0, pn=1, ps=1332 server | 3.252 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.252 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.252 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.252 DEBUG ECN probing: sent 2 probes server | 3.252 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.252 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.252 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.252 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 17.705311ms server | 3.252 DEBUG Setting timeout of 17.705311ms server | 3.252 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.252 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.252 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.252 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 17.682708ms server | 3.252 DEBUG Setting timeout of 17.682708ms server | 3.271 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.271 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.271 DEBUG [Server 64b40ae3ab8b1dfe] pn=2 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 32 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 2684, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.271 DEBUG ECN probing: sent 3 probes server | 3.272 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.272 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.272 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.272 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 57.972778ms server | 3.272 DEBUG Setting timeout of 57.972778ms server | 3.272 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.272 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.272 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.272 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 57.94737ms server | 3.272 DEBUG Setting timeout of 57.94737ms server | 3.331 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.331 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.331 DEBUG CRYPTO for ap offset=273, len=32 server | 3.331 DEBUG [Server 64b40ae3ab8b1dfe] pn=3 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 107 server | TX -> Crypto { offset: 273, len: 32 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 164, 213, 88, 11, 255, 201, 47, 112, 218, 209, 16, 122, 253, 249, 42, 184, 18, 2, 130, 10, 174, 143, 146, 222, 67, 40, 140, 57, 53, 76, 75, 189, 0, 51, 193, 15] } server | 3.331 DEBUG packet_sent this=0x561856b956d0, pn=3, ps=107 server | 3.331 DEBUG ECN probing: sent 4 probes server | 3.331 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.331 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.331 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.331 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 5.044568ms server | 3.331 DEBUG Setting timeout of 5.044568ms server | 3.331 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.331 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.331 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.331 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 5.014501ms server | 3.331 DEBUG Setting timeout of 5.014501ms server | 3.337 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.337 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.337 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.337 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 4.539838186s server | 3.337 DEBUG Setting timeout of 4.539838186s server | 3.337 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.337 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 3.337 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.337 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 4.539810634s server | 3.337 DEBUG Setting timeout of 4.539810634s client | >>>>>>>> request time cost:3018591 us, speed:0 K/s client | >>>>>>>> user_stream[0x555ec48de2f0], req: /rabcqnzrfq, send_body_size:11, recv_body_size:1024 server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] pn=1 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 59 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [161, 27, 215, 137, 116, 156, 178, 14], stateless_reset_token: [167, 246, 123, 160, 128, 64, 125, 118, 110, 9, 32, 210, 105, 235, 166, 206] } server | 4.751 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 307, tv_nsec: 807358582 } server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.751 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.751 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 20ms server | 4.751 DEBUG Setting timeout of 20ms server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] pn=2 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 59 server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [229, 154, 191, 109, 255, 162, 37, 187], stateless_reset_token: [246, 48, 8, 229, 155, 167, 13, 17, 148, 197, 47, 39, 58, 238, 54, 134] } server | 4.751 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 307, tv_nsec: 787463358 } server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.751 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] pn=4 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 31 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | 4.751 DEBUG ECN probing: sent 5 probes server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.751 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.751 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 3.125764549s server | 4.751 DEBUG Setting timeout of 3.125764549s server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.751 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.751 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.751 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 3.125733812s server | 4.751 DEBUG Setting timeout of 3.125733812s server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] pn=3 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 27, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] Rx ACK space=ap, ranges=[0..=1] server | 4.754 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=1 server | 4.754 DEBUG on_packets_acked this=0x561856b956d0, limited=1, bytes_in_flight=107, cwnd=12320, state=SlowStart, new_acked=2615 server | 4.754 DEBUG PMTUD probe of size 1380 succeeded server | 4.754 DEBUG PMTUD started with probe size 1420 server | 4.754 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 4.754 DEBUG Acked crypto frame space=ap offset=0 length=273 server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.754 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.754 DEBUG Sending PMTUD probe of size 1420, count 1 server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] pn=5 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 } server | TX -> Padding { len: 1343 } server | 4.754 DEBUG packet_sent this=0x561856b956d0, pn=5, ps=1372 server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.754 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.754 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.754 DEBUG ECN probing: sent 6 probes server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.754 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.754 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 11.665449ms server | 4.754 DEBUG Setting timeout of 11.665449ms server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.754 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.754 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.754 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 11.640923ms server | 4.754 DEBUG Setting timeout of 11.640923ms server | 4.767 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.767 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.767 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.767 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 3.783985418s server | 4.767 DEBUG Setting timeout of 3.783985418s server | 4.767 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.767 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.767 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.767 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 3.783961092s server | 4.767 DEBUG Setting timeout of 3.783961092s server | 4.858 DEBUG [Server 64b40ae3ab8b1dfe] pn=4 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 3245, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 4.858 DEBUG [Server 64b40ae3ab8b1dfe] Rx ACK space=ap, ranges=[0..=3] server | 4.858 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=3 server | 4.858 DEBUG on_packets_acked this=0x561856b956d0, limited=1, bytes_in_flight=1372, cwnd=12320, state=SlowStart, new_acked=107 server | 4.858 DEBUG Acked crypto frame space=ap offset=273 length=32 server | 4.858 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.858 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.858 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.858 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 3.131037016s server | 4.858 DEBUG Setting timeout of 3.131037016s server | 4.858 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.858 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 4.858 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.858 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 3.130920627s server | 4.858 DEBUG Setting timeout of 3.130920627s server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] pn=5 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 3215, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] Rx ACK space=ap, ranges=[0..=5] server | 6.282 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=5 server | 6.282 DEBUG on_packets_acked this=0x561856b956d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1372 server | 6.282 DEBUG PMTUD probe of size 1420 succeeded server | 6.282 DEBUG PMTUD started with probe size 1470 server | 6.282 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 6.282 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 6.282 DEBUG Sending PMTUD probe of size 1470, count 1 server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] pn=6 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 1422 server | TX -> Ping server | TX -> Padding { len: 1395 } server | 6.282 DEBUG packet_sent this=0x561856b956d0, pn=6, ps=1422 server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 6.282 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 6.282 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 6.282 DEBUG ECN probing: sent 7 probes server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 6.282 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 6.282 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 2.811443467s server | 6.282 DEBUG Setting timeout of 2.811443467s server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 6.282 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 6.282 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 6.282 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 2.811410045s server | 6.282 DEBUG Setting timeout of 2.811410045s server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] pn=6 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 3219, first_ack_range: 6, ack_ranges: [], ecn_count: None } server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] Rx ACK space=ap, ranges=[0..=6] server | 7.809 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=6 server | 7.809 DEBUG on_packets_acked this=0x561856b956d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1422 server | 7.809 DEBUG PMTUD probe of size 1470 succeeded server | 7.809 DEBUG PMTUD started with probe size 1500 server | 7.809 DEBUG PLPMTU changed from 1372 to 1422, updating pacer server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 7.809 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 7.809 DEBUG Sending PMTUD probe of size 1500, count 1 server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] pn=7 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 1452 server | TX -> Ping server | TX -> Padding { len: 1425 } server | 7.809 DEBUG packet_sent this=0x561856b956d0, pn=7, ps=1452 server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 7.809 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 7.809 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 7.809 DEBUG ECN probing: sent 8 probes server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 7.809 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 7.809 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 2.493548395s server | 7.809 DEBUG Setting timeout of 2.493548395s server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 7.809 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 7.809 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 7.809 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 2.493521474s server | 7.809 DEBUG Setting timeout of 2.493521474s server | 9.337 DEBUG [Server 64b40ae3ab8b1dfe] pn=7 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 3217, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | 9.337 DEBUG [Server 64b40ae3ab8b1dfe] Rx ACK space=ap, ranges=[0..=7] server | 9.337 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=7 server | 9.337 DEBUG on_packets_acked this=0x561856b956d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1452 server | 9.337 DEBUG PMTUD probe of size 1500 succeeded server | 9.337 INFO PMTUD stopped, PLPMTU is now 1500, raise timer Some(Instant { tv_sec: 912, tv_nsec: 373338484 }) server | 9.337 DEBUG PLPMTU changed from 1422 to 1452, updating pacer server | 9.337 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 9.337 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 9.337 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 9.337 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 30s server | 9.337 DEBUG Setting timeout of 30s server | 9.337 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 9.337 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 9.337 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 9.337 DEBUG [Server 64b40ae3ab8b1dfe] delay duration 29.999864046s server | 9.337 DEBUG Setting timeout of 29.999864046s client | [HQ-req] send_bytes:17, recv_bytes:1024, path_info:0-1-1-17-1024 client | task[0], fin_cnt: 1, fin_flag: 1 server | 16.201 DEBUG [Server 64b40ae3ab8b1dfe] pn=8 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, NotEct) len 35 server | -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" } server | 16.201 INFO [Server 64b40ae3ab8b1dfe] ConnectionClose received. Error code: Transport(0) frame type 0 reason server | 16.201 DEBUG [Server 64b40ae3ab8b1dfe] State change from Confirmed -> Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 326, tv_nsec: 2022974 } } server | 16.201 DEBUG [pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935] Path validated Instant { tv_sec: 319, tv_nsec: 237364500 } server | 16.201 DEBUG [Server 64b40ae3ab8b1dfe] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 16.201 DEBUG Building Short dcid Some(CID [8]: 287af5d37f72585c) server | 16.201 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 319, tv_nsec: 237364500 } server | 16.201 DEBUG [Server 64b40ae3ab8b1dfe] pn=8 type=Short pri-path:287af5d37f72585c [::]:443->[::ffff:193.167.0.100]:42935 Tos(Cs0, Ect0) len 35 server | TX -> Ack { largest_acknowledged: 8, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" } server | 16.201 DEBUG ECN probing: sent 9 probes server | 16.201 DEBUG Setting timeout of 6.764473407s server | 16.201 DEBUG Setting timeout of 6.764459081s server | 17.702 DEBUG [Server ...] Dropped received packet: State Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 326, tv_nsec: 2022974 } }; Total: 3 server | 17.702 DEBUG Setting timeout of 5.263661296s server | 17.702 DEBUG Setting timeout of 5.263621371s server | 22.967 DEBUG [Server 64b40ae3ab8b1dfe] State change from Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 326, tv_nsec: 2022974 } } -> Closed(Transport(Peer(0))) server | 22.968 INFO Closing timer expired 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-11 19:38:44,620 Using the client's key log file. 2025-09-11 19:38:44,628 2025-09-11 19:38:44,628 Using the client's key log file. 2025-09-11 19:38:44,629 Using the client's key log file. 2025-09-11 19:38:44,636 2025-09-11 19:38:44,636 Using the client's key log file. 2025-09-11 19:38:44,845 Check of downloaded files succeeded. 2025-09-11 19:38:45,700 Check of downloaded files succeeded.