2025-04-30 09:11:40,617 Generated random file: kwsjqcuvjs of size: 1024 2025-04-30 09:11:40,617 Requests: https://server4:443/kwsjqcuvjs 2025-04-30 09:11:40,677 2025-04-30 09:11:40,678 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_ik6_dlq7/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_txyxig_1/ DOWNLOADS=/tmp/download_8y333cw2/ SERVER_LOGS=/tmp/logs_server_8w5bindq CLIENT_LOGS=/tmp/logs_client_e0s2kw8w SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=stammw/quinn-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/kwsjqcuvjs" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 09:12:08,407 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... client | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Executing client 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.Ym9rTXCIvm server | + mkdir -p /neqo/db server | + certutil -N -d sql:/neqo/db --empty-password client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.Ym9rTXCIvm server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.Ym9rTXCIvm -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 | 6d:1f:f8:c2:c7:91:a3:59:06:07:54:af:37:c1:e3:a4: server | d0:b6:9d:f3 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner Root Certificate Authority" server | Validity: server | Not Before: Wed Apr 30 09:11:40 2025 server | Not After : Sat May 10 09:11: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:56:df:c2:0d:46:6f:af:f4:4e:f4:50:85:6c:b2:36: server | 67:67:e3:ad:5c:f9:a6:69:ca:bc:b2:a3:d8:94:b3:5d: server | ea:cc:a3:dd:30:60:47:e4:b3:42:ff:3e:9b:08:19:38: server | dc:83:49:8e:d5:9c:ef:f4:ec:80:1d:dc:0b:f2:89:69: server | cc 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 | 54:2b:64:2b:6c:14:52:0b:91:f1:80:2c:ff:db:3e:b8: server | 15:ef:d2:f9 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | dc:26:90:5a:00:62:7f:da:96:13:d0:2a:17:fc:19:85: server | b0:dc:98:3e server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:20:74:52:59:bc:9c:09:3f:30:9f:68:30:6a: server | d6:fa:d4:84:6b:09:09:59:e9:c9:57:27:73:d6:7b:1e: server | a5:29:09:56:02:21:00:c9:69:c0:b3:2d:74:6e:ed:91: server | a7:93:26:f3:b6:cf:d8:3b:07:64:e4:2f:f0:0b:bd:ef: server | 85:7b:f8:03:b2:0a:65 server | Fingerprint (SHA-256): server | 3F:C7:6A:FB:35:E7:E9:CC:22:62:38:35:86:72:0C:A5:3C:8A:97:09:22:40:2A:D1:11:57:F7:66:84:00:50:BC server | Fingerprint (SHA1): server | 72:15:AF:90:56:7B:48:E7:8C:BF:FE:40:D2:D2:45:6F:DE:BE:62:57 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 212992 server | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing server | 0.002 INFO Server waiting for connection on: [::]:443 server | 0.800 DEBUG [Server] Unsupported version: 57414954 server | 0.800 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52612 IpTos(Cs0, NotEct) len 15 sim | server:443 is available after 1.064472205s server | 0.801 DEBUG [Server] Unsupported version: 57414954 server | 0.801 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52612 IpTos(Cs0, NotEct) len 15 server | 0.801 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.801 DEBUG [Server] Unsupported version: 57414954 server | 0.801 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52612 IpTos(Cs0, NotEct) len 15 server | 0.801 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) sim | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25 sim | tcpdump: tcpdump: listening on eth0listening on eth1, link-type EN10MB (Ethernet), link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | , snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds server | 4.730 DEBUG [Server] Handle initial server | 4.730 INFO AddressValidation: no token; accepting server | 4.730 INFO [Server] Accept connection CID [20]: 187bcf847e0cde286d1257ec33238f5c33d30891 server | 4.730 DEBUG Overwrite initial version Version1 ==> Version1 server | 4.732 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 4.732 INFO [Server ...] Received valid Initial packet with scid CID [8]: db4221cc0e121f3a dcid CID [20]: 187bcf847e0cde286d1257ec33238f5c33d30891 server | 4.732 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=187bcf847e0cde286d1257ec33238f5c33d30891 server | 4.732 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 4.732 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 4.732 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] State change from Init -> WaitInitial server | 4.732 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 1200 server | -> RX Crypto { offset: 0, len: 286 } server | -> RX Padding { len: 855 } server | 4.732 DEBUG Read Ok(286) bytes server | 4.732 DEBUG 0-RTT: no token, no 0-RTT server | 4.733 DEBUG Read secret available for Handshake: SymKey [32]: 445baf39388de555511eac76307c385bd48506e34c3730d96b569c28a687af76 server | 4.733 DEBUG Write secret available for Handshake: SymKey [32]: 902d55f0fee34795d6461e981b63db8047e08177c72387e33c184a69254e991d server | 4.733 DEBUG Writing transport parameters, msg=8 server | 4.733 DEBUG Read secret available for ApplicationData: SymKey [32]: 2234f7fff7001a736563b138bb9d33c24a652fb6a41a5ea61ba600a6dfa718b1 server | 4.733 DEBUG Write secret available for ApplicationData: SymKey [32]: 2f5b2364a8ab213ad816139afa2deae5c8ffc5faefa15dc9b4b33e13d3511a46 server | 4.733 DEBUG [Agent 0x55acf999d1d0] state -> InProgress server | 4.733 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 4.733 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 4.733 DEBUG [Crypto] Handshake keys installed server | 4.733 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 4.733 DEBUG [Crypto] Application write key installed server | 4.733 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 233, tv_nsec: 271083398 } server | 4.733 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:37283] Make permanent server | 4.733 DEBUG [unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283] set as primary path server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] State change from WaitInitial -> Handshaking server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.733 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 4.733 DEBUG CRYPTO for in offset=0, len=90 server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=0 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 147 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 90 } server | 4.733 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 4.733 DEBUG CRYPTO for hs offset=0, len=735 server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=0 type=Handshake pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 930 server | TX -> Crypto { offset: 0, len: 735 } server | 4.733 DEBUG packet_sent this=0x55acf999cb40, pn=0, ps=783 server | 4.733 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pad Initial from 930 to PLPMTU 1232 server | 4.733 DEBUG packet_sent this=0x55acf999cb40, pn=0, ps=449 server | 4.733 DEBUG ECN probing: sent 1 probes server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.733 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 4.733 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 4.733 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 4.733 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 296.93966ms server | 4.733 DEBUG Setting timeout of 296.93966ms server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.733 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 4.733 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 4.733 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 4.733 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.733 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 296.903372ms server | 4.733 DEBUG Setting timeout of 296.903372ms server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=1 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 1200 server | -> RX Crypto { offset: 0, len: 286 } server | -> RX Padding { len: 855 } server | 5.026 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 233, tv_nsec: 566523355 } server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.026 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.026 DEBUG CRYPTO for in offset=0, len=90 server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=1 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 147 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 90 } server | 5.026 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.026 DEBUG CRYPTO for hs offset=0, len=735 server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=1 type=Handshake pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 930 server | TX -> Crypto { offset: 0, len: 735 } server | 5.026 DEBUG packet_sent this=0x55acf999cb40, pn=1, ps=783 server | 5.026 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pad Initial from 930 to PLPMTU 1232 server | 5.026 DEBUG packet_sent this=0x55acf999cb40, pn=1, ps=449 server | 5.026 DEBUG ECN probing: sent 2 probes server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.026 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.026 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.026 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.026 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 299.79616ms server | 5.026 DEBUG Setting timeout of 299.79616ms server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.026 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.026 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.026 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.026 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.026 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 299.763239ms server | 5.026 DEBUG Setting timeout of 299.763239ms server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=2 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 1200 server | -> RX Crypto { offset: 0, len: 286 } server | -> RX Padding { len: 855 } server | 5.027 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 233, tv_nsec: 567398155 } server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.027 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.027 DEBUG CRYPTO for in offset=0, len=90 server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=2 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 147 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 90 } server | 5.027 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.027 DEBUG CRYPTO for hs offset=0, len=735 server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=2 type=Handshake pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 930 server | TX -> Crypto { offset: 0, len: 735 } server | 5.027 DEBUG packet_sent this=0x55acf999cb40, pn=2, ps=783 server | 5.027 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pad Initial from 930 to PLPMTU 1232 server | 5.027 DEBUG packet_sent this=0x55acf999cb40, pn=2, ps=449 server | 5.027 DEBUG ECN probing: sent 3 probes server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.027 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.027 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.027 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.027 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 299.846975ms server | 5.027 DEBUG Setting timeout of 299.846975ms server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.027 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.027 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.027 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.027 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.027 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 299.808754ms server | 5.027 DEBUG Setting timeout of 299.808754ms server | 5.328 DEBUG [LossRecovery] PTO timer fired for Initial server | 5.328 DEBUG [LossRecovery] PTO timer fired for Handshake server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] }) server | 5.328 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 233, tv_nsec: 271083398 } server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 5.328 INFO Lost crypto frame space=in offset=0 length=90 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: EcnEct0 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1, smallest: 0, ack_needed: true }] }) server | 5.328 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 233, tv_nsec: 566523355 } server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 5.328 INFO Lost crypto frame space=in offset=0 length=90 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: EcnEct0 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 2, smallest: 0, ack_needed: true }] }) server | 5.328 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 233, tv_nsec: 567398155 } server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 5.328 INFO Lost crypto frame space=in offset=0 length=90 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: EcnEct0 server | 5.328 DEBUG ECN validation failed, all 3 initial marked packets were lost server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 735 }) server | 5.328 INFO Lost crypto frame space=hs offset=0 length=735 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 735 }) server | 5.328 INFO Lost crypto frame space=hs offset=0 length=735 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 735 }) server | 5.328 INFO Lost crypto frame space=hs offset=0 length=735 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 5.328 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.328 DEBUG CRYPTO for in offset=0, len=90 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=3 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 150 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 37708, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 90 } server | 5.328 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.328 DEBUG CRYPTO for hs offset=0, len=735 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=3 type=Handshake pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 933 server | TX -> Crypto { offset: 0, len: 735 } server | 5.328 DEBUG packet_sent this=0x55acf999cb40, pn=3, ps=783 server | 5.328 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pad Initial from 933 to PLPMTU 1232 server | 5.328 DEBUG packet_sent this=0x55acf999cb40, pn=3, ps=449 server | 5.328 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.329 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.329 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.329 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.329 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.329 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 599.718345ms server | 5.329 DEBUG Setting timeout of 599.718345ms server | 5.329 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.329 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.329 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.329 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.329 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.329 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 599.681657ms server | 5.329 DEBUG Setting timeout of 599.681657ms server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=3 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 1200 server | -> RX Crypto { offset: 0, len: 286 } server | -> RX Padding { len: 855 } server | 5.627 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 234, tv_nsec: 167482495 } server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.627 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.627 DEBUG CRYPTO for in offset=0, len=90 server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=4 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 147 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 90 } server | 5.627 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.627 DEBUG CRYPTO for hs offset=0, len=735 server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=4 type=Handshake pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 930 server | TX -> Crypto { offset: 0, len: 735 } server | 5.627 DEBUG packet_sent this=0x55acf999cb40, pn=4, ps=783 server | 5.627 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pad Initial from 930 to PLPMTU 1232 server | 5.627 DEBUG packet_sent this=0x55acf999cb40, pn=4, ps=449 server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.627 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.627 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.627 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.627 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 599.775161ms server | 5.627 DEBUG Setting timeout of 599.775161ms server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.627 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.627 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.627 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.627 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 599.733223ms server | 5.627 DEBUG Setting timeout of 599.733223ms server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=4 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 1200 server | -> RX Crypto { offset: 0, len: 286 } server | -> RX Padding { len: 855 } server | 5.627 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 234, tv_nsec: 168304507 } server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.627 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.627 DEBUG CRYPTO for in offset=0, len=90 server | 5.627 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=5 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 147 server | TX -> Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 90 } server | 5.628 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.628 DEBUG CRYPTO for hs offset=0, len=735 server | 5.628 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=5 type=Handshake pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 930 server | TX -> Crypto { offset: 0, len: 735 } server | 5.628 DEBUG packet_sent this=0x55acf999cb40, pn=5, ps=783 server | 5.628 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.628 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pad Initial from 930 to PLPMTU 1232 server | 5.628 DEBUG packet_sent this=0x55acf999cb40, pn=5, ps=449 server | 5.628 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.628 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.628 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.628 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.628 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.628 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 880.989µs server | 5.628 DEBUG Setting timeout of 880.989µs server | 5.628 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.628 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.628 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.628 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.628 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 5.628 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 851.053µs server | 5.628 DEBUG Setting timeout of 851.053µs server | 5.630 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.630 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.630 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.630 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.630 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.630 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 597.794232ms server | 5.630 DEBUG Setting timeout of 597.794232ms server | 5.630 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.630 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.630 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 5.630 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 5.630 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 5.630 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 597.762843ms server | 5.630 DEBUG Setting timeout of 597.762843ms server | 6.228 DEBUG [LossRecovery] PTO timer fired for Initial server | 6.229 DEBUG [LossRecovery] PTO timer fired for Handshake server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 2, smallest: 0, ack_needed: true }] }) server | 6.229 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 233, tv_nsec: 869065300 } server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 6.229 INFO Lost crypto frame space=in offset=0 length=90 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 3, smallest: 0, ack_needed: true }] }) server | 6.229 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 234, tv_nsec: 167482495 } server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 6.229 INFO Lost crypto frame space=in offset=0 length=90 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 4, smallest: 0, ack_needed: true }] }) server | 6.229 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 234, tv_nsec: 168304507 } server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 6.229 INFO Lost crypto frame space=in offset=0 length=90 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 735 }) server | 6.229 INFO Lost crypto frame space=hs offset=0 length=735 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 735 }) server | 6.229 INFO Lost crypto frame space=hs offset=0 length=735 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 735 }) server | 6.229 INFO Lost crypto frame space=hs offset=0 length=735 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 6.229 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 6.229 DEBUG CRYPTO for in offset=0, len=90 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=6 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 150 server | TX -> Ack { largest_acknowledged: 4, ack_delay: 75130, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 90 } server | 6.229 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 6.229 DEBUG CRYPTO for hs offset=0, len=735 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=6 type=Handshake pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 933 server | TX -> Crypto { offset: 0, len: 735 } server | 6.229 DEBUG packet_sent this=0x55acf999cb40, pn=6, ps=783 server | 6.229 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pad Initial from 933 to PLPMTU 1232 server | 6.229 DEBUG packet_sent this=0x55acf999cb40, pn=6, ps=449 server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.229 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 6.229 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 6.229 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.229 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 1.199728805s server | 6.229 DEBUG Setting timeout of 1.199728805s server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.229 DEBUG Building Initial dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 6.229 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 6.229 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.229 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.229 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 1.199694s server | 6.229 DEBUG Setting timeout of 1.199694s server | 6.236 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=5 type=Initial pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 53 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 47, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | 6.236 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Rx ACK space=in, ranges=[0..=0] server | 6.236 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 6.236 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8175/12769 ssthresh 18446744073709551615] slow start += 449 server | 6.236 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8175/12769 ssthresh 18446744073709551615] on_packets_acked this=0x55acf999cb40, limited=0, bytes_in_flight=8175, cwnd=12769, state=SlowStart, new_acked=449 server | 6.236 DEBUG Acked crypto frame space=in offset=0 length=90 server | 6.236 DEBUG [pri-unv-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283] Path validated Instant { tv_sec: 234, tv_nsec: 777095281 } server | 6.236 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Drop packet number space in server | 6.236 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 6.236 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=0 type=Handshake pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 1147 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 47, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | -> RX Crypto { offset: 0, len: 36 } server | -> RX Padding { len: 1056 } server | 6.236 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Rx ACK space=hs, ranges=[0..=0] server | 6.236 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 6.236 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4698/13552 ssthresh 18446744073709551615] slow start += 783 server | 6.236 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4698/13552 ssthresh 18446744073709551615] on_packets_acked this=0x55acf999cb40, limited=0, bytes_in_flight=4698, cwnd=13552, state=SlowStart, new_acked=783 server | 6.236 DEBUG Acked crypto frame space=hs offset=0 length=735 server | 6.236 DEBUG Read Ok(36) bytes server | 6.237 DEBUG [Agent 0x55acf999d1d0] 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 | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] TLS connection complete server | 6.237 DEBUG [pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283] Path validated Instant { tv_sec: 234, tv_nsec: 777095281 } server | 6.237 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 6.237 DEBUG [Crypto] application read keys installed server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] State change from Handshaking -> Connected server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] State change from Connected -> Confirmed server | 6.237 DEBUG PMTUD started with probe size 1380 server | 6.237 INFO [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Connection established server | 6.237 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 234, tv_nsec: 777095281 } server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.237 DEBUG Building Handshake dcid Some(CID [8]: db4221cc0e121f3a) scid Some(CID [10]: e1a7789dc65c160c1857) server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=7 type=Handshake pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 52 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Drop packet number space hs server | 6.237 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 6.237 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=0 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [140, 109, 12, 17, 59, 84, 24, 181, 29, 52], stateless_reset_token: [177, 67, 174, 127, 154, 189, 48, 28, 129, 3, 143, 196, 107, 125, 130, 110] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [52, 150, 14, 110, 168, 160, 81, 214, 204, 62], stateless_reset_token: [112, 180, 236, 9, 253, 178, 30, 229, 195, 247, 32, 204, 221, 223, 142, 69] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [176, 48, 89, 0, 163, 119, 143, 251, 48, 210], stateless_reset_token: [69, 193, 215, 150, 45, 132, 134, 200, 50, 1, 96, 154, 188, 13, 255, 102] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [3, 64, 29, 194, 12, 147, 0, 84, 181, 141], stateless_reset_token: [52, 235, 100, 117, 45, 180, 188, 233, 149, 228, 169, 4, 143, 84, 212, 76] } server | TX -> Padding { len: 1133 } server | 6.237 DEBUG packet_sent this=0x55acf999cb40, pn=0, ps=1280 server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.237 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.237 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 22.132673ms server | 6.237 DEBUG Setting timeout of 22.132673ms server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=0 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 224 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [149, 192, 6, 84, 92, 169, 230, 144], stateless_reset_token: [112, 140, 116, 241, 92, 170, 190, 113, 141, 221, 149, 137, 186, 238, 16, 115] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [68, 115, 222, 204, 16, 167, 101, 118], stateless_reset_token: [24, 56, 138, 171, 80, 113, 27, 27, 42, 248, 77, 241, 150, 48, 233, 139] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [228, 64, 108, 20, 68, 99, 69, 216], stateless_reset_token: [245, 173, 94, 55, 58, 82, 145, 93, 228, 153, 182, 178, 104, 251, 89, 34] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [117, 163, 232, 50, 170, 153, 164, 1], stateless_reset_token: [45, 91, 167, 110, 85, 229, 31, 174, 212, 169, 88, 99, 182, 107, 78, 63] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [109, 58, 203, 75, 248, 84, 68, 36], stateless_reset_token: [231, 46, 219, 147, 163, 184, 32, 151, 169, 236, 211, 101, 104, 63, 56, 1] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [28, 206, 78, 189, 185, 52, 214, 249], stateless_reset_token: [179, 98, 204, 118, 231, 36, 109, 174, 152, 72, 62, 198, 131, 240, 226, 147] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [137, 159, 237, 253, 105, 12, 218, 94], stateless_reset_token: [55, 194, 77, 151, 50, 67, 193, 235, 162, 42, 243, 197, 252, 58, 62, 135] } server | 6.237 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 234, tv_nsec: 797772052 } server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.237 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.237 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 20ms server | 6.237 DEBUG Setting timeout of 20ms server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=1 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 48 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 6.237 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 234, tv_nsec: 777865456 } server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.237 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=1 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 34 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.237 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.237 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.237 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 23.82512ms server | 6.237 DEBUG Setting timeout of 23.82512ms server | 6.238 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] send session ticket 406f0014187bcf847e0cde286d1257ec33238f5c33d308910104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0ae1a7789dc65c160c1857110c000000015aeaea3a000000016ab200c0000000ff02de1a0243e8200100686921 server | 6.238 DEBUG Path = 'kwsjqcuvjs' server | 6.238 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.238 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.238 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.238 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 22.828995ms server | 6.238 DEBUG Setting timeout of 22.828995ms server | 6.262 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.262 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.262 DEBUG Sending PMTUD probe of size 1380, count 1 server | 6.262 DEBUG CRYPTO for ap offset=0, len=273 server | 6.262 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=2 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 1332 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true } server | TX -> Crypto { offset: 0, len: 273 } server | 6.262 DEBUG packet_sent this=0x55acf999cb40, pn=2, ps=1332 server | 6.262 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.262 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.262 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.262 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 68.286582ms server | 6.262 DEBUG Setting timeout of 68.286582ms server | 6.262 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.262 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.262 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.262 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 68.256777ms server | 6.262 DEBUG Setting timeout of 68.256777ms server | 6.332 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.332 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.332 DEBUG CRYPTO for ap offset=273, len=48 server | 6.332 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=3 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 123 server | TX -> Crypto { offset: 273, len: 48 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 126, 198, 160, 30, 39, 125, 244, 4, 138, 103, 237, 57, 125, 62, 209, 213, 9, 153, 14, 251, 146, 223, 232, 117, 142, 202, 166, 231, 121, 40, 172, 194, 3, 121, 206, 185] } server | 6.332 DEBUG packet_sent this=0x55acf999cb40, pn=3, ps=123 server | 6.332 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.332 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.332 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.332 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 5.662974ms server | 6.332 DEBUG Setting timeout of 5.662974ms server | 6.332 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.332 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.332 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 6.332 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 5.631174ms server | 6.332 DEBUG Setting timeout of 5.631174ms server | 6.339 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.339 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.339 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.339 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.781677065s server | 6.339 DEBUG Setting timeout of 3.781677065s server | 6.339 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.339 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.339 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.339 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.781651918s server | 6.339 DEBUG Setting timeout of 3.781651918s server | 6.528 WARN [Server ...] Dropped received packet: Decryption failure; Total: 1 server | 6.528 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.528 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.528 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.528 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.593018062s server | 6.528 DEBUG Setting timeout of 3.593018062s server | 6.528 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.528 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.528 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.528 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.592939315s server | 6.528 DEBUG Setting timeout of 3.592939315s server | 6.528 WARN [Server ...] Dropped received packet: Decryption failure; Total: 2 server | 6.528 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.528 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.528 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.528 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.592381897s server | 6.528 DEBUG Setting timeout of 3.592381897s server | 6.528 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.528 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.528 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.528 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.592333376s server | 6.528 DEBUG Setting timeout of 3.592333376s server | 6.830 WARN [Server ...] Dropped received packet: Decryption failure; Total: 3 server | 6.830 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.830 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.830 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.830 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.290487867s server | 6.830 DEBUG Setting timeout of 3.290487867s server | 6.830 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.830 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 6.830 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 6.830 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.290410222s server | 6.830 DEBUG Setting timeout of 3.290410222s server | 7.129 WARN [Server ...] Dropped received packet: Decryption failure; Total: 4 server | 7.129 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.129 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.129 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.129 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 2.992143069s server | 7.129 DEBUG Setting timeout of 2.992143069s server | 7.129 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.129 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.129 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.129 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 2.992065976s server | 7.129 DEBUG Setting timeout of 2.992065976s server | 7.129 WARN [Server ...] Dropped received packet: Decryption failure; Total: 5 server | 7.129 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.129 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.129 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.129 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 2.991432284s server | 7.129 DEBUG Setting timeout of 2.991432284s server | 7.129 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.129 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.129 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.129 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 2.991389835s server | 7.129 DEBUG Setting timeout of 2.991389835s server | 7.731 WARN [Server ...] Dropped received packet: Decryption failure; Total: 6 server | 7.731 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.731 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.731 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.731 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 2.390187304s server | 7.731 DEBUG Setting timeout of 2.390187304s server | 7.731 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.731 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.731 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 7.731 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 2.390106213s server | 7.731 DEBUG Setting timeout of 2.390106213s server | 7.764 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=2 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 0, Ce: 0})) } server | 7.764 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Rx ACK space=ap, ranges=[0..=2] server | 7.764 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 7.764 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 123/16164 ssthresh 18446744073709551615] slow start += 2612 server | 7.764 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 123/16164 ssthresh 18446744073709551615] on_packets_acked this=0x55acf999cb40, limited=0, bytes_in_flight=123, cwnd=16164, state=SlowStart, new_acked=2612 server | 7.764 DEBUG PMTUD probe of size 1380 succeeded server | 7.764 DEBUG PMTUD started with probe size 1420 server | 7.764 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 7.764 DEBUG Acked crypto frame space=ap offset=0 length=273 server | 7.764 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 7.764 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.764 DEBUG Sending PMTUD probe of size 1420, count 1 server | 7.764 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=4 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 1372 server | TX -> Ping server | TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 } server | TX -> Padding { len: 1343 } server | 7.764 DEBUG packet_sent this=0x55acf999cb40, pn=4, ps=1372 server | 7.764 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 7.764 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.764 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 7.764 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] delay duration 3.227449399s server | 7.764 DEBUG Setting timeout of 3.227449399s server | 7.765 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=3 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, Ect0) len 39 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 30, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 0, Ce: 0})) } server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" } server | 7.765 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] Rx ACK space=ap, ranges=[0..=2] server | 7.765 INFO [Server 187bcf847e0cde286d1257ec33238f5c33d30891] ConnectionClose received. Error code: Application(0) frame type 0 reason server | 7.765 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 245, tv_nsec: 988499577 } } server | 7.765 DEBUG [pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283] Path validated Instant { tv_sec: 236, tv_nsec: 305366526 } server | 7.765 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 7.765 DEBUG Building Short dcid Some(CID [8]: db4221cc0e121f3a) server | 7.765 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 236, tv_nsec: 305366526 } server | 7.765 DEBUG [Server 187bcf847e0cde286d1257ec33238f5c33d30891] pn=5 type=Short pri-path:db4221cc0e121f3a [::]:443->[::ffff:193.167.0.100]:37283 IpTos(Cs0, NotEct) len 38 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 7.765 DEBUG Setting timeout of 9.683002608s server | 7.765 DEBUG Setting timeout of 9.682992318s server | 7.833 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 245, tv_nsec: 988499577 } }; Total: 7 server | 7.833 DEBUG Setting timeout of 9.614975319s server | 7.833 DEBUG Setting timeout of 9.614937017s server | 9.266 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 245, tv_nsec: 988499577 } }; Total: 8 server | 9.266 DEBUG Setting timeout of 8.181272518s server | 9.266 DEBUG Setting timeout of 8.18123613s client exited with code 0 Aborting on container exit... Container server Stopping Container client Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 09:12:08,504 Using the client's key log file. 2025-04-30 09:12:08,513 2025-04-30 09:12:08,514 Using the client's key log file. 2025-04-30 09:12:08,514 Using the client's key log file. 2025-04-30 09:12:08,522 2025-04-30 09:12:08,523 Using the client's key log file. 2025-04-30 09:12:08,820 Check of downloaded files succeeded. 2025-04-30 09:12:10,021 Check of downloaded files succeeded.