2025-12-13 09:40:40,273 Generated random file: colossal-steaming-musician of size: 1024 2025-12-13 09:40:40,273 Requests: https://server4:443/colossal-steaming-musician 2025-12-13 09:40:40,331 2025-12-13 09:40:40,331 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_00z1d7ho/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_jx3mqtpf/ DOWNLOADS=/tmp/download_oxcynj1s/ SERVER_LOGS=/tmp/logs_server_vavojkic CLIENT_LOGS=/tmp/logs_client_qmkvw7a4 SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=martenseemann/quic-go-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/colossal-steaming-musician" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-12-13 09:40:55,237 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 client | Using commit: add7529015e46cda13441129d3081020f1baddc1 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 server | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin server | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin server | + '[' -n handshake ']' server | + '[' -n /logs/qlog/ ']' server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert server | ++ mktemp server | + P12CERT=/tmp/tmp.llymR3EdKd server | + mkdir -p /neqo/db client | wait-for-it.sh: waiting 10 seconds for sim:57832 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.llymR3EdKd server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.llymR3EdKd -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 | 03:d4:21:ba:49:d8:8f:cc:cd:06:06:d8:ec:00:c2:64: server | 82:14:00:99 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner Root Certificate Authority" server | Validity: server | Not Before: Sat Dec 13 09:40:40 2025 server | Not After : Tue Dec 23 09:40:40 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:5f:22:21:62:59:f7:ca:8f:ac:f4:9f:66:31:fc:bf: server | 72:9f:fa:71:8e:36:35:34:7c:2f:b9:37:b3:e3:a6:b2: server | 54:f7:41:de:06:6b:be:85:ce:4b:5a:26:47:30:65:ab: server | 79:50:8c:bc:1d:5a:48:ea:32:4b:1f:8b:99:43:17:5f: server | c7 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 | 51:81:8f:ae:16:22:31:a3:c3:e0:4d:31:0d:14:55:43: server | 02:0f:61:32 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 53:40:1c:f9:c6:c8:e8:15:34:72:8a:eb:5f:36:e3:6b: server | d2:5b:d0:85 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:20:45:3c:40:11:b9:20:9f:ea:a6:25:56:3f: server | 2d:48:98:35:45:18:8a:11:9d:7e:ce:ed:3a:0d:03:7e: server | 8e:d3:dd:5f:02:21:00:8d:97:f5:fa:ad:ad:e5:53:63: server | 39:1f:f4:3c:86:7d:d7:b1:0a:ce:0e:97:d6:94:55:28: server | 7b:8d:1c:e8:76:45:57 server | Fingerprint (SHA-256): server | C3:90:95:24:79:D7:91:5D:6C:94:45:92:98:FF:34:56:15:DF:09:D9:B0:21:54:02:B5:F0:01:FF:13:54:58:68 server | Fingerprint (SHA1): server | 61:64:0B:A6:67:AF:D3:BD:87:BF:7F:51:3E:88:30:B7:4A:29:1C:90 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.291 DEBUG [Server] Unsupported version: 57414954 server | 0.291 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:47631 Tos(Cs0, NotEct) len 15 sim | server:443 is available after 500.751184ms sim | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25 sim | Capturing on 'eth1' sim | Capturing on 'eth0' sim | File: /logs/trace_node_right.pcap sim | File: /logs/trace_node_left.pcap client | wait-for-it.sh: sim:57832 is available after 1 seconds client | Starting QUIC client... client | Client params: client | Test case: handshake server | 1.720 DEBUG [Server] Handle initial server | 1.720 INFO AddressValidation: no token; accepting server | 1.720 INFO [Server] Accept connection CID [17]: 30ca0201f442d1148e85d5523137b66b38 server | 1.720 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.722 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.722 INFO [Server ...] Received valid Initial packet with scid CID [0]: dcid CID [17]: 30ca0201f442d1148e85d5523137b66b38 server | 1.722 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=30ca0201f442d1148e85d5523137b66b38 server | 1.722 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=17 server | 1.722 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.722 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] State change from Init -> WaitInitial server | 1.722 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Crypto { offset: 67, len: 1164 } server | -> RX Crypto { offset: 0, len: 63 } server | 1.722 DEBUG unmark 0-length range at 0 server | 1.722 DEBUG unmark 0-length range at 0 server | 1.722 DEBUG [Agent 0x559e4c895ee0] state -> InProgress server | 1.722 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 202, tv_nsec: 773095682 } server | 1.722 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:37358] Make permanent server | 1.722 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:37358] set as primary path server | 1.722 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] State change from WaitInitial -> WaitVersion server | 1.722 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.722 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.722 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=17 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 42 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.722 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.722 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.722 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.722 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 298.035561ms server | 1.722 DEBUG Setting timeout of 298.035561ms server | 1.722 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Padding { len: 941 } server | -> RX Crypto { offset: 63, len: 4 } server | -> RX Crypto { offset: 1231, len: 282 } server | 1.722 DEBUG [Agent 0x559e4c895ee0] state -> InProgress server | 1.722 DEBUG 0-RTT: no token, no 0-RTT server | 1.723 DEBUG Read secret available for Handshake: SymKey [32]: 6e654da9606624c206e7675a1ac3368165b99193da7d1a94105df02f889871bf server | 1.723 DEBUG Write secret available for Handshake: SymKey [32]: 605259aa9bee3c9d0b5992c218be3195c23f2381b12acdd8f0b67a535e3479c9 server | 1.723 DEBUG Writing transport parameters, msg=8 server | 1.724 DEBUG Read secret available for ApplicationData: SymKey [32]: 424ed2290b81f76222dc58bc85365791fefcc6e82f02151be026487e7d2beb69 server | 1.724 DEBUG Write secret available for ApplicationData: SymKey [32]: 717e3399c61f48bb723b5a9a85762cb2a32fb7983b18609bb0263d9a79b5bccc server | 1.724 DEBUG [Agent 0x559e4c895ee0] state -> InProgress server | 1.724 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.724 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.724 DEBUG [Crypto] Handshake keys installed server | 1.724 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.724 DEBUG [Crypto] Application write key installed server | 1.724 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 202, tv_nsec: 775090718 } server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] State change from WaitVersion -> Handshaking server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.724 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.724 DEBUG CRYPTO for in offset=0, len=1178 server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=18 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1224 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 1178 } server | 1.724 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pad Initial from 1224 to PLPMTU 1232 server | 1.724 DEBUG packet_sent this=0x559e4c906c50, pn=18, ps=1232 server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.724 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.724 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.724 DEBUG CRYPTO for hs offset=0, len=734 server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 774 server | TX -> Crypto { offset: 0, len: 734 } server | 1.724 DEBUG packet_sent this=0x559e4c906c50, pn=0, ps=774 server | 1.724 DEBUG Building Short dcid Some(CID [0]: ) server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.724 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.724 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.724 DEBUG Building Short dcid Some(CID [0]: ) server | 1.724 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 298.039168ms server | 1.724 DEBUG Setting timeout of 298.039168ms server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.724 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.724 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.724 DEBUG Building Short dcid Some(CID [0]: ) server | 1.724 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.724 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 298.005335ms server | 1.724 DEBUG Setting timeout of 298.005335ms server | 1.915 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=2 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Crypto { offset: 67, len: 1164 } server | -> RX Crypto { offset: 0, len: 63 } server | 1.916 DEBUG unmark 0-length range at 0 server | 1.916 DEBUG unmark 0-length range at 0 server | 1.916 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 202, tv_nsec: 968503067 } server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.916 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.916 DEBUG CRYPTO for in offset=0, len=1178 server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=19 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1224 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 1178 } server | 1.916 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pad Initial from 1224 to PLPMTU 1232 server | 1.916 DEBUG packet_sent this=0x559e4c906c50, pn=19, ps=1232 server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.916 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.916 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.916 DEBUG CRYPTO for hs offset=0, len=734 server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 774 server | TX -> Crypto { offset: 0, len: 734 } server | 1.916 DEBUG packet_sent this=0x559e4c906c50, pn=1, ps=774 server | 1.916 DEBUG Building Short dcid Some(CID [0]: ) server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.916 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.916 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.916 DEBUG Building Short dcid Some(CID [0]: ) server | 1.916 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 299.800278ms server | 1.916 DEBUG Setting timeout of 299.800278ms server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.916 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.916 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.916 DEBUG Building Short dcid Some(CID [0]: ) server | 1.916 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 299.764632ms server | 1.916 DEBUG Setting timeout of 299.764632ms server | 1.916 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=3 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Padding { len: 941 } server | -> RX Crypto { offset: 1231, len: 282 } server | -> RX Crypto { offset: 63, len: 4 } server | 1.917 DEBUG unmark 0-length range at 0 server | 1.917 DEBUG unmark 0-length range at 0 server | 1.917 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 202, tv_nsec: 969515753 } server | 1.917 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.917 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.917 DEBUG CRYPTO for in offset=0, len=1178 server | 1.917 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=20 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1224 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 1178 } server | 1.917 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.917 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pad Initial from 1224 to PLPMTU 1232 server | 1.917 DEBUG packet_sent this=0x559e4c906c50, pn=20, ps=1232 server | 1.917 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.917 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.917 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.917 DEBUG Building Short dcid Some(CID [0]: ) server | 1.917 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.917 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.917 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.917 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.917 DEBUG Building Short dcid Some(CID [0]: ) server | 1.917 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.917 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 2.008566ms server | 1.917 DEBUG Setting timeout of 2.008566ms server | 1.917 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.917 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.917 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.917 DEBUG Building Short dcid Some(CID [0]: ) server | 1.917 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.917 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.980063ms server | 1.917 DEBUG Setting timeout of 1.980063ms server | 1.920 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.920 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.920 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.920 DEBUG CRYPTO for hs offset=0, len=734 server | 1.920 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=2 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 774 server | TX -> Crypto { offset: 0, len: 734 } server | 1.920 DEBUG packet_sent this=0x559e4c906c50, pn=2, ps=774 server | 1.920 DEBUG Building Short dcid Some(CID [0]: ) server | 1.920 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.920 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.920 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.920 DEBUG Building Short dcid Some(CID [0]: ) server | 1.920 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.920 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.963969ms server | 1.920 DEBUG Setting timeout of 1.963969ms server | 1.920 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.920 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.920 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.920 DEBUG Building Short dcid Some(CID [0]: ) server | 1.920 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.920 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.938592ms server | 1.920 DEBUG Setting timeout of 1.938592ms server | 1.923 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.923 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.923 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.923 DEBUG Building Short dcid Some(CID [0]: ) server | 1.923 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.923 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 293.567772ms server | 1.923 DEBUG Setting timeout of 293.567772ms server | 1.923 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.923 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.923 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 1.923 DEBUG Building Short dcid Some(CID [0]: ) server | 1.923 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.923 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 293.539138ms server | 1.923 DEBUG Setting timeout of 293.539138ms server | 2.218 DEBUG [recovery::Loss] PTO timer fired for Initial server | 2.218 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] }) server | 2.218 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 202, tv_nsec: 773095682 } server | 2.218 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1, smallest: 0, ack_needed: true }] }) server | 2.218 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 202, tv_nsec: 775090718 } server | 2.218 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1178 }) server | 2.218 INFO Lost crypto frame space=in offset=0 length=1178 server | 2.218 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 2.218 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.218 DEBUG CRYPTO for in offset=0, len=1178 server | 2.218 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=21 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1227 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 37738, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 1178 } server | 2.219 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.219 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pad Initial from 1227 to PLPMTU 1232 server | 2.219 DEBUG packet_sent this=0x559e4c906c50, pn=21, ps=1232 server | 2.219 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Handshake), paced: false } server | 2.219 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.219 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.219 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=3 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 37 server | TX -> Ping server | 2.219 DEBUG packet_sent this=0x559e4c906c50, pn=3, ps=39 server | 2.219 DEBUG Building Short dcid Some(CID [0]: ) server | 2.219 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.219 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.219 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.219 DEBUG Building Short dcid Some(CID [0]: ) server | 2.219 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.219 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 599.746758ms server | 2.219 DEBUG Setting timeout of 599.746758ms server | 2.219 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.219 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.219 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.219 DEBUG Building Short dcid Some(CID [0]: ) server | 2.219 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.219 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 599.709639ms server | 2.219 DEBUG Setting timeout of 599.709639ms server | 2.316 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=4 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Crypto { offset: 67, len: 1164 } server | -> RX Crypto { offset: 0, len: 63 } server | 2.316 DEBUG unmark 0-length range at 0 server | 2.316 DEBUG unmark 0-length range at 0 server | 2.316 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 203, tv_nsec: 369379925 } server | 2.316 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.316 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.316 DEBUG CRYPTO for in offset=0, len=1178 server | 2.316 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=22 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1224 server | TX -> Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 1178 } server | 2.316 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.316 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pad Initial from 1224 to PLPMTU 1232 server | 2.316 DEBUG packet_sent this=0x559e4c906c50, pn=22, ps=1232 server | 2.316 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.316 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.316 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.316 DEBUG CRYPTO for hs offset=0, len=734 server | 2.316 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=4 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 774 server | TX -> Crypto { offset: 0, len: 734 } server | 2.317 DEBUG packet_sent this=0x559e4c906c50, pn=4, ps=774 server | 2.317 DEBUG Building Short dcid Some(CID [0]: ) server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.317 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Short dcid Some(CID [0]: ) server | 2.317 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 599.774239ms server | 2.317 DEBUG Setting timeout of 599.774239ms server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.317 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Short dcid Some(CID [0]: ) server | 2.317 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 599.733664ms server | 2.317 DEBUG Setting timeout of 599.733664ms server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=5 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Padding { len: 941 } server | -> RX Crypto { offset: 1231, len: 282 } server | -> RX Crypto { offset: 63, len: 4 } server | 2.317 DEBUG unmark 0-length range at 0 server | 2.317 DEBUG unmark 0-length range at 0 server | 2.317 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 203, tv_nsec: 370308565 } server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.317 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG CRYPTO for in offset=0, len=1178 server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=23 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1224 server | TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 1178 } server | 2.317 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pad Initial from 1224 to PLPMTU 1232 server | 2.317 DEBUG packet_sent this=0x559e4c906c50, pn=23, ps=1232 server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.317 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Short dcid Some(CID [0]: ) server | 2.317 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.317 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Short dcid Some(CID [0]: ) server | 2.317 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 2.074045ms server | 2.317 DEBUG Setting timeout of 2.074045ms server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.317 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.317 DEBUG Building Short dcid Some(CID [0]: ) server | 2.317 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 2.317 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 2.037947ms server | 2.317 DEBUG Setting timeout of 2.037947ms server | 2.321 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.321 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.321 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.321 DEBUG CRYPTO for hs offset=0, len=734 server | 2.321 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=5 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 774 server | TX -> Crypto { offset: 0, len: 734 } server | 2.321 DEBUG packet_sent this=0x559e4c906c50, pn=5, ps=774 server | 2.321 DEBUG Building Short dcid Some(CID [0]: ) server | 2.321 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1019, pto: None, probe: EnumSet(), paced: false } server | 2.321 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.321 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.321 DEBUG Building Short dcid Some(CID [0]: ) server | 2.321 DEBUG TX blocked, profile=SendProfile { limit: 1019, pto: None, probe: EnumSet(), paced: false } server | 2.321 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 596.660074ms server | 2.321 DEBUG Setting timeout of 596.660074ms server | 2.321 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1019, pto: None, probe: EnumSet(), paced: false } server | 2.321 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.321 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.321 DEBUG Building Short dcid Some(CID [0]: ) server | 2.321 DEBUG TX blocked, profile=SendProfile { limit: 1019, pto: None, probe: EnumSet(), paced: false } server | 2.321 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 596.629267ms server | 2.321 DEBUG Setting timeout of 596.629267ms server | 2.918 DEBUG [recovery::Loss] PTO timer fired for Initial server | 2.918 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 3, smallest: 0, ack_needed: true }] }) server | 2.918 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 202, tv_nsec: 969515753 } server | 2.918 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1178 }) server | 2.918 INFO Lost crypto frame space=in offset=0 length=1178 server | 2.918 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 3, smallest: 0, ack_needed: true }] }) server | 2.918 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 203, tv_nsec: 271423521 } server | 2.918 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1178 }) server | 2.918 INFO Lost crypto frame space=in offset=0 length=1178 server | 2.918 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 2.918 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.918 DEBUG CRYPTO for in offset=0, len=1178 server | 2.918 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=24 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1227 server | TX -> Ack { largest_acknowledged: 5, ack_delay: 75141, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 1178 } server | 2.919 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.919 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pad Initial from 1227 to PLPMTU 1232 server | 2.919 DEBUG packet_sent this=0x559e4c906c50, pn=24, ps=1232 server | 2.919 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Handshake), paced: false } server | 2.919 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.919 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.919 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=6 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 37 server | TX -> Ping server | 2.919 DEBUG packet_sent this=0x559e4c906c50, pn=6, ps=39 server | 2.919 DEBUG Building Short dcid Some(CID [0]: ) server | 2.919 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 2.919 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.919 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.919 DEBUG Building Short dcid Some(CID [0]: ) server | 2.919 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 2.919 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.199775472s server | 2.919 DEBUG Setting timeout of 1.199775472s server | 2.919 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 2.919 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.919 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 2.919 DEBUG Building Short dcid Some(CID [0]: ) server | 2.919 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 2.919 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.19973684s server | 2.919 DEBUG Setting timeout of 1.19973684s server | 3.116 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=6 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Crypto { offset: 67, len: 1164 } server | -> RX Crypto { offset: 0, len: 63 } server | 3.117 DEBUG unmark 0-length range at 0 server | 3.117 DEBUG unmark 0-length range at 0 server | 3.117 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 204, tv_nsec: 169478466 } server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.117 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=25 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 42 server | TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: None } server | 3.117 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.117 DEBUG Building Short dcid Some(CID [0]: ) server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.117 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.117 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.117 DEBUG Building Short dcid Some(CID [0]: ) server | 3.117 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.001796025s server | 3.117 DEBUG Setting timeout of 1.001796025s server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.117 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.117 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.117 DEBUG Building Short dcid Some(CID [0]: ) server | 3.117 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.001757122s server | 3.117 DEBUG Setting timeout of 1.001757122s server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=7 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Padding { len: 941 } server | -> RX Crypto { offset: 63, len: 4 } server | -> RX Crypto { offset: 1231, len: 282 } server | 3.117 DEBUG unmark 0-length range at 0 server | 3.117 DEBUG unmark 0-length range at 0 server | 3.117 DEBUG unmark 0-length range at 0 server | 3.117 DEBUG unmark 0-length range at 0 server | 3.117 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 204, tv_nsec: 170430429 } server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.117 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.117 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=26 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 42 server | TX -> Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | 3.117 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.118 DEBUG Building Short dcid Some(CID [0]: ) server | 3.118 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.118 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.118 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.118 DEBUG Building Short dcid Some(CID [0]: ) server | 3.118 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.118 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.000871543s server | 3.118 DEBUG Setting timeout of 1.000871543s server | 3.118 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.118 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.118 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.118 DEBUG Building Short dcid Some(CID [0]: ) server | 3.118 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.118 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 1.000834594s server | 3.118 DEBUG Setting timeout of 1.000834594s server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=8 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 1280 server | -> RX Ack { largest_acknowledged: 18, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | -> RX Padding { len: 1237 } server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Rx ACK space=in, ranges=[17..=18] server | 3.227 DEBUG [recovery::Loss] ACK for Initial - largest_acked=18 server | 3.227 DEBUG on_packets_acked this=0x559e4c906c50, limited=1, bytes_in_flight=11340, cwnd=12320, state=SlowStart, new_acked=1232 server | 3.227 DEBUG Acked crypto frame space=in offset=0 length=1178 server | 3.227 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37358] Path validated Instant { tv_sec: 204, tv_nsec: 280201628 } server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 980, pto: None, probe: EnumSet(), paced: false } server | 3.227 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.227 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.227 DEBUG CRYPTO for hs offset=0, len=734 server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=7 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 774 server | TX -> Crypto { offset: 0, len: 734 } server | 3.227 DEBUG packet_sent this=0x559e4c906c50, pn=7, ps=774 server | 3.227 DEBUG Building Short dcid Some(CID [0]: ) server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.227 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.227 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.227 DEBUG Building Short dcid Some(CID [0]: ) server | 3.227 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 4.206356935s server | 3.227 DEBUG Setting timeout of 4.206356935s server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Drop packet number space in server | 3.227 DEBUG [recovery::Loss] Reset loss recovery state for Initial server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=0 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, NotEct) len 81 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Crypto { offset: 0, len: 36 } server | 3.227 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Rx ACK space=hs, ranges=[0..=0] server | 3.227 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=0 server | 3.227 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 3948/13094 ssthresh 18446744073709551615] slow start += 774 server | 3.227 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 3948/13094 ssthresh 18446744073709551615] on_packets_acked this=0x559e4c906c50, limited=0, bytes_in_flight=3948, cwnd=13094, state=SlowStart, new_acked=774 server | 3.227 DEBUG Acked crypto frame space=hs offset=0 length=734 server | 3.228 DEBUG [Agent 0x559e4c895ee0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] TLS connection complete server | 3.228 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:37358] Path validated Instant { tv_sec: 204, tv_nsec: 280449206 } server | 3.228 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 3.228 DEBUG [Crypto] application read keys installed server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] State change from Handshaking -> Connected server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] State change from Connected -> Confirmed server | 3.228 DEBUG PMTUD started, PLPMTU is now 1280 server | 3.228 DEBUG PMTUD started with probe size 1380 server | 3.228 INFO [Server 30ca0201f442d1148e85d5523137b66b38] Connection established server | 3.228 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 204, tv_nsec: 280449206 } server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.228 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b83d94eaf8cb43de0cd5) server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=8 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, Ect0) len 41 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] Drop packet number space hs server | 3.228 DEBUG [recovery::Loss] Reset loss recovery state for Handshake server | 3.228 DEBUG Building Short dcid Some(CID [0]: ) server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [78, 97, 243, 190, 10, 40, 161, 149, 26, 14], stateless_reset_token: Token([78, 53, 115, 241, 210, 33, 193, 227, 131, 17, 146, 135, 227, 143, 247, 197]) } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [112, 40, 69, 208, 135, 141, 134, 91, 250, 96], stateless_reset_token: Token([180, 142, 44, 235, 222, 105, 92, 186, 25, 114, 126, 60, 216, 43, 84, 229]) } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [159, 28, 175, 120, 6, 70, 80, 99, 241, 156], stateless_reset_token: Token([242, 212, 31, 39, 55, 70, 82, 42, 111, 230, 153, 32, 27, 53, 2, 116]) } server | TX -> Padding { len: 1182 } server | 3.228 DEBUG packet_sent this=0x559e4c906c50, pn=0, ps=1291 server | 3.228 DEBUG ECN probing: sent 1 probes server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.228 DEBUG Building Short dcid Some(CID [0]: ) server | 3.228 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 5.100633ms server | 3.228 DEBUG Setting timeout of 5.100633ms server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, Ect0) len 64 server | -> RX Stream { stream_id: 0, offset: 0, len: >>33, fin: true } server | 3.228 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 204, tv_nsec: 300887633 } server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.228 DEBUG Building Short dcid Some(CID [0]: ) server | 3.228 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.228 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 5.079123ms server | 3.228 DEBUG Setting timeout of 5.079123ms server | 3.229 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] send session ticket 406b001130ca0201f442d1148e85d5523137b66b380104800075300404802000000504801000000604801000000704801000000801100901100b01140e01080f0ab83d94eaf8cb43de0cd5110c000000010adaba3a000000016ab200c0000000ff02de1a0243e820048000ffff686921 server | 3.229 DEBUG Path = 'colossal-steaming-musician' server | 3.229 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.229 DEBUG Building Short dcid Some(CID [0]: ) server | 3.229 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.229 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 4.003119ms server | 3.229 DEBUG Setting timeout of 4.003119ms server | 3.234 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.234 DEBUG Building Short dcid Some(CID [0]: ) server | 3.234 DEBUG Sending PMTUD probe of size 1380, count 1 server | 3.234 DEBUG CRYPTO for ap offset=0, len=281 server | 3.234 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37358 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: 281 } server | 3.234 DEBUG packet_sent this=0x559e4c906c50, pn=1, ps=1332 server | 3.234 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.234 DEBUG Building Short dcid Some(CID [0]: ) server | 3.234 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.234 DEBUG ECN probing: sent 2 probes server | 3.234 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.234 DEBUG Building Short dcid Some(CID [0]: ) server | 3.234 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.234 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 13.72944ms server | 3.234 DEBUG Setting timeout of 13.72944ms server | 3.234 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.234 DEBUG Building Short dcid Some(CID [0]: ) server | 3.234 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.234 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 13.706558ms server | 3.234 DEBUG Setting timeout of 13.706558ms server | 3.249 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG Building Short dcid Some(CID [0]: ) server | 3.249 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, Ect0) len 27 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 2673, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | 3.249 DEBUG ECN probing: sent 3 probes server | 3.249 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG Building Short dcid Some(CID [0]: ) server | 3.249 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 61.816065ms server | 3.249 DEBUG Setting timeout of 61.816065ms server | 3.249 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG Building Short dcid Some(CID [0]: ) server | 3.249 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 61.787442ms server | 3.249 DEBUG Setting timeout of 61.787442ms server | 3.312 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.312 DEBUG Building Short dcid Some(CID [0]: ) server | 3.312 DEBUG CRYPTO for ap offset=281, len=40 server | 3.312 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37358 Tos(Cs0, Ect0) len 107 server | TX -> Crypto { offset: 281, len: 40 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 136, 77, 154, 76, 2, 10, 124, 176, 168, 115, 51, 61, 180, 198, 85, 17, 113, 220, 13, 115, 176, 193, 239, 224, 254, 79, 111, 83, 190, 220, 14, 222, 43, 136, 16, 76] } server | 3.312 DEBUG packet_sent this=0x559e4c906c50, pn=3, ps=107 server | 3.312 DEBUG ECN probing: sent 4 probes server | 3.312 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.312 DEBUG Building Short dcid Some(CID [0]: ) server | 3.312 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.312 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 5.709946ms server | 3.312 DEBUG Setting timeout of 5.709946ms server | 3.312 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.312 DEBUG Building Short dcid Some(CID [0]: ) server | 3.312 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.312 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 5.683447ms server | 3.312 DEBUG Setting timeout of 5.683447ms server | 3.319 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.319 DEBUG Building Short dcid Some(CID [0]: ) server | 3.319 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.319 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.781844895s server | 3.319 DEBUG Setting timeout of 3.781844895s server | 3.319 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.319 DEBUG Building Short dcid Some(CID [0]: ) server | 3.319 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.319 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.781821792s server | 3.319 DEBUG Setting timeout of 3.781821792s server | 3.418 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 1 server | 3.418 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.418 DEBUG Building Short dcid Some(CID [0]: ) server | 3.418 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.418 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.682681826s server | 3.418 DEBUG Setting timeout of 3.682681826s server | 3.418 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.418 DEBUG Building Short dcid Some(CID [0]: ) server | 3.418 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.418 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.682638626s server | 3.418 DEBUG Setting timeout of 3.682638626s server | 3.421 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 2 server | 3.421 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.421 DEBUG Building Short dcid Some(CID [0]: ) server | 3.421 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.421 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.679631334s server | 3.421 DEBUG Setting timeout of 3.679631334s server | 3.421 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.421 DEBUG Building Short dcid Some(CID [0]: ) server | 3.421 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.421 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.67958634s server | 3.421 DEBUG Setting timeout of 3.67958634s server | 3.427 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 3 server | 3.427 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.427 DEBUG Building Short dcid Some(CID [0]: ) server | 3.427 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.427 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.673405677s server | 3.427 DEBUG Setting timeout of 3.673405677s server | 3.427 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 4 server | 3.427 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.427 DEBUG Building Short dcid Some(CID [0]: ) server | 3.427 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.427 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.673378306s server | 3.427 DEBUG Setting timeout of 3.673378306s server | 3.427 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.427 DEBUG Building Short dcid Some(CID [0]: ) server | 3.427 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.427 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.673356947s server | 3.427 DEBUG Setting timeout of 3.673356947s server | 3.720 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 5 server | 3.720 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.720 DEBUG Building Short dcid Some(CID [0]: ) server | 3.720 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.720 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.380218164s server | 3.720 DEBUG Setting timeout of 3.380218164s server | 3.720 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.720 DEBUG Building Short dcid Some(CID [0]: ) server | 3.720 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.720 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.380178109s server | 3.720 DEBUG Setting timeout of 3.380178109s server | 3.819 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 6 server | 3.819 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.819 DEBUG Building Short dcid Some(CID [0]: ) server | 3.819 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.819 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.281820167s server | 3.819 DEBUG Setting timeout of 3.281820167s server | 3.819 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.819 DEBUG Building Short dcid Some(CID [0]: ) server | 3.819 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.819 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.281782276s server | 3.819 DEBUG Setting timeout of 3.281782276s server | 3.822 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 7 server | 3.822 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.822 DEBUG Building Short dcid Some(CID [0]: ) server | 3.822 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.822 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.278846548s server | 3.822 DEBUG Setting timeout of 3.278846548s server | 3.822 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.822 DEBUG Building Short dcid Some(CID [0]: ) server | 3.822 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.822 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.27881545s server | 3.822 DEBUG Setting timeout of 3.27881545s server | 3.828 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 8 server | 3.828 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.828 DEBUG Building Short dcid Some(CID [0]: ) server | 3.828 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.828 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.272444997s server | 3.828 DEBUG Setting timeout of 3.272444997s server | 3.828 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 9 server | 3.828 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.828 DEBUG Building Short dcid Some(CID [0]: ) server | 3.828 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.828 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.272393401s server | 3.828 DEBUG Setting timeout of 3.272393401s server | 3.828 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.828 DEBUG Building Short dcid Some(CID [0]: ) server | 3.828 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.828 DEBUG [Server 30ca0201f442d1148e85d5523137b66b38] delay duration 3.272373864s server | 3.828 DEBUG Setting timeout of 3.272373864s 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-12-13 09:40:55,320 Using the client's key log file. 2025-12-13 09:40:55,328 2025-12-13 09:40:55,329 Using the client's key log file. 2025-12-13 09:40:55,329 Using the client's key log file. 2025-12-13 09:40:55,336 2025-12-13 09:40:55,337 Using the client's key log file. 2025-12-13 09:40:55,573 Check of downloaded files succeeded. 2025-12-13 09:40:56,567 Check of downloaded files succeeded.