2025-12-13 09:48:31,847 Generated random file: chilly-maroon-professor of size: 1024 2025-12-13 09:48:31,847 Requests: https://server4:443/chilly-maroon-professor 2025-12-13 09:48:31,904 2025-12-13 09:48:31,904 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_l1sg6ce5/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_xgipbebh/ DOWNLOADS=/tmp/download_viwb8wb8/ SERVER_LOGS=/tmp/logs_server_b87isk3x CLIENT_LOGS=/tmp/logs_client_bzhf5oq_ SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=cloudflare/quiche-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/chilly-maroon-professor" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-12-13 09:48:56,551 Container sim Recreate Container sim Recreated Container server Recreate Container client Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 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 | supported 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.wWuDtKm16i server | + mkdir -p /neqo/db server | + certutil -N -d sql:/neqo/db --empty-password client | wait-for-it: waiting 30 seconds for sim:57832 server | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.wWuDtKm16i server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.wWuDtKm16i -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 | 53:d7:ca:f4:44:72:3b:6d:cd:06:a2:a5:f0:57:d2:3d: server | c6:72:ce:71 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:48:31 2025 server | Not After : Tue Dec 23 09:48:31 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:c3:bd:4e:7b:5f:17:70:4a:ca:ed:ec:c9:ea:16:b8: server | eb:eb:aa:33:31:36:91:57:2a:36:a1:37:f7:ba:ee:fd: server | 06:00:4a:b8:d0:4f:c2:26:08:9a:f9:23:55:4b:43:76: server | 76:70:84:24:62:f3:ab:ae:7d:56:2e:33:c9:03:77:a2: server | 52 server | Curve: ANSI X9.62 elliptic curve prime256v1 (aka secp256r1, NIST P-256) server | Signed Extensions: server | Name: Certificate Subject Alt Name server | DNS name: "server" server | DNS name: "server4" server | DNS name: "server6" server | DNS name: "server46" server | server | Name: Certificate Subject Key ID server | Data: server | 59:79:26:85:e6:03:c7:76:6b:a3:a4:f5:a5:1f:5f:8f: server | 17:c1:5e:91 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 80:ac:61:a1:df:5c:d5:81:26:2e:0f:55:b4:5f:77:4e: server | f0:f9:51:de server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:20:4b:2b:c2:ba:a0:90:35:bf:87:a7:d7:83: server | 0f:cb:ac:59:0c:76:43:40:1c:65:83:97:54:f2:16:bd: server | d9:ad:82:1c:02:21:00:d6:5c:ba:22:72:f1:99:80:ce: server | 64:5f:3f:74:f7:8c:72:1c:20:25:3c:07:82:04:3f:79: server | 8e:0c:85:da:f3:54:e1 server | Fingerprint (SHA-256): server | 01:11:7F:5F:20:0F:0D:5F:59:0F:F7:4D:1B:43:DF:C4:84:A1:C7:FD:A8:7F:3F:7A:5B:99:6F:57:1A:06:01:87 server | Fingerprint (SHA1): server | E9:B6:3F:C4:75:6A:69:4E:18:35:C3:AF:D6:3F:A4:D2:23:54:94:4E 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.298 DEBUG [Server] Unsupported version: 57414954 server | 0.298 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36697 Tos(Cs0, NotEct) len 15 sim | server:443 is available after 501.221926ms sim | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25 sim | Capturing on 'eth0' sim | Capturing on 'eth1' sim | File: /logs/trace_node_left.pcap sim | File: /logs/trace_node_right.pcap client | wait-for-it: sim:57832 is available after 1 seconds client | ## Starting quiche client... client | ## Client params: client | ## Requests: https://server4:443/chilly-maroon-professor client | ## Test case: handshake server | 1.730 DEBUG [Server] Handle initial server | 1.730 INFO AddressValidation: no token; accepting server | 1.730 INFO [Server] Accept connection CID [16]: 7e726d737ec98b8f0db2ca3476ae3134 server | 1.730 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.732 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.732 INFO [Server ...] Received valid Initial packet with scid CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 dcid CID [16]: 7e726d737ec98b8f0db2ca3476ae3134 server | 1.732 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=7e726d737ec98b8f0db2ca3476ae3134 server | 1.732 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=23 server | 1.732 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.732 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] State change from Init -> WaitInitial server | 1.732 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 333 server | -> RX Crypto { offset: 0, len: 266 } server | 1.732 DEBUG 0-RTT: no token, no 0-RTT server | 1.732 DEBUG Read secret available for Handshake: SymKey [32]: 639d563fa88584fc559cfa9e5f93dfff24e48b5e238805193c6864a57178b4a5 server | 1.732 DEBUG Write secret available for Handshake: SymKey [32]: bc3c0aa30c4f61b6a8bbe3ef88945ffd84937cfc52c65c3aa215c832cd6035df server | 1.732 DEBUG Writing transport parameters, msg=8 server | 1.733 DEBUG Read secret available for ApplicationData: SymKey [32]: 17bdf6f647f2d4644c343653bd5ef2df30f4e24366ab2c5efe567cf81f6bfd51 server | 1.733 DEBUG Write secret available for ApplicationData: SymKey [32]: 652daeb932c14e3cbd5cd72f375146caffb0d25fd01fdf873b71f474973f577c server | 1.733 DEBUG [Agent 0x564de9b3fee0] state -> InProgress server | 1.733 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.733 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.733 DEBUG [Crypto] Handshake keys installed server | 1.733 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.733 DEBUG [Crypto] Application write key installed server | 1.733 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 193, tv_nsec: 966957417 } server | 1.733 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:55278] Make permanent server | 1.733 DEBUG [unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278] set as primary path server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] State change from WaitInitial -> Handshaking server | 1.733 DEBUG [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1 server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.733 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 1.733 DEBUG CRYPTO for in offset=0, len=90 server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=23 type=Initial pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 156 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 1.733 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 1.733 DEBUG CRYPTO for hs offset=0, len=731 server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=0 type=Handshake pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 947 server | TX -> Crypto { offset: 0, len: 731 } server | 1.733 DEBUG packet_sent this=0x564de9bb0c50, pn=0, ps=791 server | 1.733 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pad Initial from 947 to PLPMTU 1232 server | 1.733 DEBUG packet_sent this=0x564de9bb0c50, pn=23, ps=441 server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.733 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 1.733 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 1.733 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 1.733 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 296.877583ms server | 1.733 DEBUG Setting timeout of 296.877583ms server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.733 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 1.733 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 1.733 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 1.733 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.733 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 296.837399ms server | 1.733 DEBUG Setting timeout of 296.837399ms server | 2.030 DEBUG [recovery::Loss] PTO timer fired for Initial server | 2.031 DEBUG [recovery::Loss] PTO timer fired for Handshake server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] }) server | 2.031 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 193, tv_nsec: 966957417 } server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 2.031 INFO Lost crypto frame space=in offset=0 length=90 server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 731 }) server | 2.031 INFO Lost crypto frame space=hs offset=0 length=731 server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 2.031 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.031 DEBUG CRYPTO for in offset=0, len=90 server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=24 type=Initial pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 159 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 37575, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 2.031 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.031 DEBUG CRYPTO for hs offset=0, len=731 server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=1 type=Handshake pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 950 server | TX -> Crypto { offset: 0, len: 731 } server | 2.031 DEBUG packet_sent this=0x564de9bb0c50, pn=1, ps=791 server | 2.031 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pad Initial from 950 to PLPMTU 1232 server | 2.031 DEBUG packet_sent this=0x564de9bb0c50, pn=24, ps=441 server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 2.031 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.031 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.031 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 2.031 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 2.031 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.031 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.031 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 2.031 DEBUG TX blocked, profile=SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 29.699108708s server | 2.031 DEBUG Setting timeout of 29.699108708s server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 2.031 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.031 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.031 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 2.031 DEBUG TX blocked, profile=SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false } server | 2.031 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 29.69906711s server | 2.031 DEBUG Setting timeout of 29.69906711s server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=1 type=Initial pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 333 server | -> RX Crypto { offset: 0, len: 266 } server | 2.725 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 194, tv_nsec: 962331008 } server | 2.725 DEBUG [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 2 server | 2.725 DEBUG [recovery::Loss] PTO timer fired for Initial server | 2.725 DEBUG [recovery::Loss] PTO timer fired for Handshake server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] }) server | 2.725 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 194, tv_nsec: 267561073 } server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 2.725 INFO Lost crypto frame space=in offset=0 length=90 server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 731 }) server | 2.725 INFO Lost crypto frame space=hs offset=0 length=731 server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 2.725 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.725 DEBUG CRYPTO for in offset=0, len=90 server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=25 type=Initial pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 156 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 2.725 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.725 DEBUG CRYPTO for hs offset=0, len=731 server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=2 type=Handshake pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 947 server | TX -> Crypto { offset: 0, len: 731 } server | 2.725 DEBUG packet_sent this=0x564de9bb0c50, pn=2, ps=791 server | 2.725 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pad Initial from 947 to PLPMTU 1232 server | 2.725 DEBUG packet_sent this=0x564de9bb0c50, pn=25, ps=441 server | 2.725 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 2.725 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.725 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.725 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 2.725 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 2.726 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.726 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.726 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.726 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 2.726 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.726 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 1.199731058s server | 2.726 DEBUG Setting timeout of 1.199731058s server | 2.726 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.726 DEBUG Building Initial dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.726 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 2.726 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 2.726 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.726 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 1.199685193s server | 2.726 DEBUG Setting timeout of 1.199685193s server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=2 type=Initial pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 63 server | -> RX Ack { largest_acknowledged: 23, ack_delay: 86, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Rx ACK space=in, ranges=[23..=23] server | 3.237 DEBUG [recovery::Loss] ACK for Initial - largest_acked=23 server | 3.237 DEBUG on_packets_acked this=0x564de9bb0c50, limited=1, bytes_in_flight=3255, cwnd=12320, state=SlowStart, new_acked=441 server | 3.237 DEBUG Acked crypto frame space=in offset=0 length=90 server | 3.237 DEBUG [pri-unv-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278] Path validated Instant { tv_sec: 195, tv_nsec: 473818055 } server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Drop packet number space in server | 3.237 DEBUG [recovery::Loss] Reset loss recovery state for Initial server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=3 type=Handshake pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 102 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 66, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Crypto { offset: 0, len: 36 } server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Rx ACK space=hs, ranges=[0..=0] server | 3.237 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=0 server | 3.237 DEBUG on_packets_acked this=0x564de9bb0c50, limited=1, bytes_in_flight=1582, cwnd=12320, state=SlowStart, new_acked=791 server | 3.237 DEBUG Acked crypto frame space=hs offset=0 length=731 server | 3.237 DEBUG [Agent 0x564de9b3fee0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] TLS connection complete server | 3.237 DEBUG [pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278] Path validated Instant { tv_sec: 195, tv_nsec: 473818055 } server | 3.237 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 3.237 DEBUG [Crypto] application read keys installed server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] State change from Handshaking -> Connected server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] State change from Connected -> Confirmed server | 3.237 DEBUG PMTUD started, PLPMTU is now 1280 server | 3.237 DEBUG PMTUD started with probe size 1380 server | 3.237 INFO [Server 7e726d737ec98b8f0db2ca3476ae3134] Connection established server | 3.237 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 195, tv_nsec: 473818055 } server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=4 type=Short pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 1185 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [96, 17, 174, 129, 180, 154, 171, 179, 181, 151, 22, 14, 18, 121, 245, 98, 244, 64, 145, 137], stateless_reset_token: Token([40, 129, 30, 25, 128, 159, 108, 238, 4, 241, 21, 133, 152, 220, 157, 215]) } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [124, 117, 3, 127, 241, 179, 45, 221, 189, 113, 230, 36, 45, 87, 195, 74, 101, 97, 27, 219], stateless_reset_token: Token([24, 205, 125, 158, 111, 138, 101, 46, 180, 144, 179, 59, 146, 136, 192, 189]) } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [244, 1, 71, 50, 156, 34, 159, 85, 213, 33, 217, 24, 2, 119, 53, 146, 76, 78, 254, 23], stateless_reset_token: Token([249, 246, 63, 46, 98, 109, 150, 127, 84, 211, 197, 23, 65, 248, 148, 147]) } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [138, 162, 191, 250, 177, 18, 157, 166, 20, 162, 74, 236, 249, 58, 145, 200, 139, 168, 240, 255], stateless_reset_token: Token([248, 116, 9, 116, 21, 161, 84, 16, 249, 120, 137, 43, 191, 217, 225, 100]) } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [216, 21, 2, 186, 251, 116, 143, 48, 52, 217, 5, 65, 6, 77, 252, 72, 185, 225, 240, 40], stateless_reset_token: Token([237, 41, 33, 100, 199, 119, 147, 126, 195, 197, 123, 84, 65, 159, 57, 135]) } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [73, 234, 114, 8, 217, 20, 49, 81, 97, 35, 162, 91, 97, 102, 46, 100, 31, 41, 129, 161], stateless_reset_token: Token([2, 219, 185, 131, 181, 42, 80, 26, 95, 9, 137, 171, 212, 197, 199, 185]) } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [69, 195, 208, 31, 104, 12, 21, 56, 202, 75, 231, 27, 69, 207, 97, 212, 1, 158, 70, 43], stateless_reset_token: Token([31, 236, 240, 180, 91, 93, 85, 197, 227, 118, 81, 136, 87, 55, 58, 160]) } server | -> RX Stream { stream_id: 0, offset: 0, len: 30, fin: true } server | -> RX Padding { len: 842 } server | 3.237 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 195, tv_nsec: 473818055 } server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.237 DEBUG Building Handshake dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) scid Some(CID [10]: ae295602a0ebe70dc9e7) server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=3 type=Handshake pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, Ect0) len 61 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Drop packet number space hs server | 3.237 DEBUG [recovery::Loss] Reset loss recovery state for Handshake server | 3.237 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.237 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=0 type=Short pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, Ect0) len 1332 server | TX -> Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [93, 37, 89, 95, 146, 165, 138, 183, 138, 14], stateless_reset_token: Token([88, 95, 123, 161, 13, 81, 177, 69, 119, 108, 12, 160, 240, 10, 118, 75]) } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [182, 90, 231, 86, 232, 115, 160, 76, 14, 122], stateless_reset_token: Token([68, 51, 216, 249, 165, 96, 186, 151, 168, 67, 211, 221, 89, 132, 100, 231]) } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [145, 238, 241, 50, 202, 101, 207, 184, 191, 47], stateless_reset_token: Token([116, 36, 72, 96, 102, 163, 142, 210, 180, 97, 99, 125, 34, 121, 17, 168]) } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [102, 227, 206, 197, 26, 116, 203, 115, 95, 161], stateless_reset_token: Token([81, 187, 8, 164, 10, 230, 109, 35, 246, 167, 33, 113, 163, 28, 125, 189]) } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [45, 118, 233, 111, 62, 98, 72, 249, 184, 203], stateless_reset_token: Token([110, 53, 33, 153, 131, 21, 235, 97, 25, 134, 45, 193, 57, 149, 145, 189]) } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [170, 133, 70, 80, 164, 73, 50, 131, 77, 174], stateless_reset_token: Token([170, 196, 161, 210, 19, 131, 174, 199, 187, 38, 19, 177, 225, 155, 194, 64]) } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [7, 74, 221, 34, 113, 42, 23, 64, 193, 248], stateless_reset_token: Token([220, 51, 130, 250, 240, 204, 63, 140, 218, 163, 31, 193, 239, 211, 17, 195]) } server | TX -> Padding { len: 1017 } server | 3.238 DEBUG packet_sent this=0x564de9bb0c50, pn=0, ps=1271 server | 3.238 DEBUG ECN probing: sent 1 probes server | 3.238 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.238 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.238 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.238 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 1.559461ms server | 3.238 DEBUG Setting timeout of 1.559461ms server | 3.239 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] send session ticket 406a00107e726d737ec98b8f0db2ca3476ae31340104800075300404802000000504801000000604801000000704801000000801100901100b01140e01080f0aae295602a0ebe70dc9e7110c00000001ba8adaea000000016ab200c0000000ff02de1a0243e820048000ffff686921 server | 3.239 DEBUG Path = 'chilly-maroon-professor' server | 3.239 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.239 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.239 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.239 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 474.957µs server | 3.239 DEBUG Setting timeout of 474.957µs server | 3.240 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.240 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.240 DEBUG Sending PMTUD probe of size 1380, count 1 server | 3.240 DEBUG CRYPTO for ap offset=0, len=261 server | 3.240 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=1 type=Short pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 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: 261 } server | 3.240 DEBUG packet_sent this=0x564de9bb0c50, pn=1, ps=1332 server | 3.240 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.240 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.240 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.240 DEBUG ECN probing: sent 2 probes server | 3.240 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.240 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.240 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.240 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 80.713946ms server | 3.240 DEBUG Setting timeout of 80.713946ms server | 3.240 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.240 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.240 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.240 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 80.685533ms server | 3.240 DEBUG Setting timeout of 80.685533ms server | 3.322 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.322 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.322 DEBUG CRYPTO for ap offset=261, len=60 server | 3.322 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=2 type=Short pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, Ect0) len 147 server | TX -> Crypto { offset: 261, len: 60 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 159, 81, 192, 234, 39, 254, 211, 85, 26, 94, 167, 181, 136, 80, 149, 208, 250, 122, 6, 240, 197, 21, 20, 51, 152, 26, 145, 110, 244, 77, 38, 3, 195, 181, 156, 64] } server | 3.322 DEBUG packet_sent this=0x564de9bb0c50, pn=2, ps=147 server | 3.322 DEBUG ECN probing: sent 3 probes server | 3.322 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.322 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.322 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.322 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 7.288222ms server | 3.322 DEBUG Setting timeout of 7.288222ms server | 3.322 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.322 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.322 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.322 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 7.258467ms server | 3.322 DEBUG Setting timeout of 7.258467ms server | 3.330 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.330 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.330 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.330 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 3.782262304s server | 3.331 DEBUG Setting timeout of 3.782262304s server | 3.331 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.331 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 3.331 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.331 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 3.782240884s server | 3.331 DEBUG Setting timeout of 3.782240884s server | 4.740 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=5 type=Short pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 20, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 4.740 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] Rx ACK space=ap, ranges=[0..=0] server | 4.740 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=0 server | 4.740 DEBUG on_packets_acked this=0x564de9bb0c50, limited=1, bytes_in_flight=1479, cwnd=12320, state=SlowStart, new_acked=1271 server | 4.740 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.740 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 4.740 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.740 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 1.81052868s server | 4.740 DEBUG Setting timeout of 1.81052868s server | 4.740 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.740 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 4.740 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.740 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] delay duration 1.810423012s server | 4.740 DEBUG Setting timeout of 1.810423012s server | 4.741 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=6 type=Short pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, NotEct) len 38 server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye" } server | 4.741 INFO [Server 7e726d737ec98b8f0db2ca3476ae3134] ConnectionClose received. Error code: Application(0) frame type 0 reason kthxbye server | 4.741 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] State change from Confirmed -> Draining { error: Transport(PeerApplication(0)), timeout: Instant { tv_sec: 206, tv_nsec: 663590080 } } server | 4.741 DEBUG [pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278] Path validated Instant { tv_sec: 196, tv_nsec: 978505955 } server | 4.741 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.741 DEBUG Building Short dcid Some(CID [20]: 4e4e177b617c173bbd36e15128ec0a62ef5aa7d1) server | 4.741 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 196, tv_nsec: 978505955 } server | 4.741 DEBUG [Server 7e726d737ec98b8f0db2ca3476ae3134] pn=3 type=Short pri-path:4e4e177b617c173bbd36e15128ec0a62ef5aa7d1 [::]:443->[::ffff:193.167.0.100]:55278 Tos(Cs0, Ect0) len 47 server | TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 4.742 DEBUG ECN probing: sent 4 probes server | 4.742 DEBUG Setting timeout of 9.684960895s server | 4.742 DEBUG Setting timeout of 9.684949324s 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:48:56,633 Using the client's key log file. 2025-12-13 09:48:56,642 2025-12-13 09:48:56,643 Using the client's key log file. 2025-12-13 09:48:56,643 Using the client's key log file. 2025-12-13 09:48:56,651 2025-12-13 09:48:56,651 Using the client's key log file. 2025-12-13 09:48:56,865 Check of downloaded files succeeded. 2025-12-13 09:48:57,722 Check of downloaded files succeeded.