2025-04-30 10:23:42,184 Generated random file: pmsekwglsv of size: 10240 2025-04-30 10:23:42,184 Requests: https://server4:443/pmsekwglsv 2025-04-30 10:23:42,246 2025-04-30 10:23:42,247 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_nbho61nz/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_by5aurx8/ DOWNLOADS=/tmp/download_pgj80le8/ SERVER_LOGS=/tmp/logs_server_nur3didc CLIENT_LOGS=/tmp/logs_client_j10mmfri SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/ngtcp2/ngtcp2-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/pmsekwglsv" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 10:23:54,364 Container sim Created Container server Recreate Container client Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... 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 server | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Endpoint's IPv6 address is fd00:cafe:cafe::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 retry ']' server | + '[' -n /logs/qlog/ ']' server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert server | ++ mktemp server | + P12CERT=/tmp/tmp.TpzrXXgE0j 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.TpzrXXgE0j server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.TpzrXXgE0j -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 | 02:70:6f:f0:07:ce:bb:84:8e:2d:97:08:d7:3c:6e:0b: server | ee:b8:6b:52 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 10:23:42 2025 server | Not After : Sat May 10 10:23:42 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:4c:79:80:c7:ed:80:f2:cc:b5:6a:c4:04:70:ac:03: server | 6f:44:6a:0f:49:62:f3:79:4f:91:53:98:23:da:5b:6b: server | d8:7b:67:94:45:e8:9f:15:15:e3:36:70:9b:c3:db:53: server | 83:e0:42:e2:95:54:e8:75:20:35:3e:c3:f9:dc:b7:26: server | c8 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 | 90:92:2a:19:aa:c3:42:0a:00:51:c7:dd:7e:1d:ae:44: server | d4:18:b9:f8 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 68:d9:0e:7b:54:9b:ee:f5:c8:ba:2d:22:fa:5c:55:0c: server | 3a:0e:b0:3b server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:20:02:57:71:bb:1c:32:f8:6c:dc:8b:d6:77: server | b2:6a:da:dc:ef:e4:57:96:c4:73:3f:e2:0d:4c:bf:ac: server | 63:d5:82:02:02:21:00:d3:16:68:55:fb:27:be:31:c3: server | b1:1b:d6:4f:5b:35:86:6f:c6:c3:78:41:d0:6c:0c:68: server | 34:52:42:6d:37:2e:f9 server | Fingerprint (SHA-256): server | 6A:9A:2E:01:EF:8E:9B:74:9E:9E:F7:52:48:AF:43:28:2D:F9:EC:C0:82:17:A5:74:7B:4B:DA:7E:96:E2:49:6E server | Fingerprint (SHA1): server | 86:EF:7C:04:A5:A5:93:EE:39:C5:E4:28:4B:12:F1:04:6A:B5:CB:1A 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 | + '[' retry = connectionmigration ']' server | + RUST_LOG=debug server | + RUST_BACKTRACE=1 server | + neqo-server --cc cubic --qns-test retry --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.793 DEBUG [Server] Unsupported version: 57414954 server | 0.793 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:39012 IpTos(Cs0, NotEct) len 15 server | 0.793 DEBUG [Server] Unsupported version: 57414954 server | 0.793 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:39012 IpTos(Cs0, NotEct) len 15 server | 0.793 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.793 DEBUG [Server] Unsupported version: 57414954 server | 0.793 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:39012 IpTos(Cs0, NotEct) len 15 server | 0.793 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) sim | server:443 is available after 1.060315889s sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds server | 1.057 DEBUG [Server] Handle initial server | 1.057 INFO AddressValidation: no token; validating server | 1.057 INFO [Server] Send retry for CID [18]: 8e1b7709e3eed761ee16f4250c6d2179bfdc server | 1.057 DEBUG [Server] type=Retry path:8e1b7709e3eed761ee16f4250c6d2179bfdc [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, NotEct) len 111 server | 1.089 DEBUG [Server] Handle initial server | 1.089 INFO AddressValidation: valid Retry token for 8e1b7709e3eed761ee16f4250c6d2179bfdc server | 1.089 INFO [Server] Accept connection CID [18]: 8e1b7709e3eed761ee16f4250c6d2179bfdc server | 1.089 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.091 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.091 INFO [Server ...] Received valid Initial packet with scid CID [17]: af5b24df68c3196b3ef219d86c9c837288 dcid CID [10]: b0cdcc59c218f7254a42 server | 1.091 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=b0cdcc59c218f7254a42 server | 1.091 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.091 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.091 DEBUG [Server b0cdcc59c218f7254a42] State change from Init -> WaitInitial server | 1.091 DEBUG [Server b0cdcc59c218f7254a42] pn=78209889 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1200 server | -> RX Crypto { offset: 0, len: 263 } server | -> RX Padding { len: 813 } server | 1.091 DEBUG Read Ok(263) bytes server | 1.092 DEBUG 0-RTT: no token, no 0-RTT server | 1.092 DEBUG Read secret available for Handshake: SymKey [32]: 6fe5576370b0ffc5a60621174f51aaeb80a46b6e74018f3165fb98bc3a8552b5 server | 1.092 DEBUG Write secret available for Handshake: SymKey [32]: 3429a2bbf983f13ea518d5d6c46e37671d31e7b0986788f1be204f92e8efabf2 server | 1.092 DEBUG Writing transport parameters, msg=8 server | 1.092 DEBUG Read secret available for ApplicationData: SymKey [32]: 98162cbe5f183f0d3eb0dbc4f5bcc5077989c1e2e85b19408c85a0880dbafc5c server | 1.092 DEBUG Write secret available for ApplicationData: SymKey [32]: 3b95ae8b94a01a548137ca61cc7ab0cd96d33578f0651877d5faacc36f73a350 server | 1.092 DEBUG [Agent 0x555c00357710] state -> InProgress server | 1.092 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.092 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.092 DEBUG [Crypto] Handshake keys installed server | 1.092 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.092 DEBUG [Crypto] Application write key installed server | 1.092 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 247, tv_nsec: 699548947 } server | 1.092 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:55948] Make permanent server | 1.092 DEBUG [unv-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948] set as primary path server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] State change from WaitInitial -> Handshaking server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.093 DEBUG Building Initial dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) scid Some(CID [10]: 4674c49d8c0c77981654) server | 1.093 DEBUG CRYPTO for in offset=0, len=90 server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] pn=0 type=Initial pri-unv-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 159 server | TX -> Ack { largest_acknowledged: 78209889, 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 | 1.093 DEBUG Building Handshake dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) scid Some(CID [10]: 4674c49d8c0c77981654) server | 1.093 DEBUG CRYPTO for hs offset=0, len=747 server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] pn=0 type=Handshake pri-unv-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 963 server | TX -> Crypto { offset: 0, len: 747 } server | 1.093 DEBUG packet_sent this=0x555c0039eb40, pn=0, ps=804 server | 1.093 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] pad Initial from 963 to PLPMTU 1232 server | 1.093 DEBUG packet_sent this=0x555c0039eb40, pn=0, ps=428 server | 1.093 DEBUG ECN probing: sent 1 probes server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.093 DEBUG Building Initial dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) scid Some(CID [10]: 4674c49d8c0c77981654) server | 1.093 DEBUG Building Handshake dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) scid Some(CID [10]: 4674c49d8c0c77981654) server | 1.093 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.093 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] delay duration 296.740626ms server | 1.093 DEBUG Setting timeout of 296.740626ms server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.093 DEBUG Building Initial dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) scid Some(CID [10]: 4674c49d8c0c77981654) server | 1.093 DEBUG Building Handshake dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) scid Some(CID [10]: 4674c49d8c0c77981654) server | 1.093 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.093 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.093 DEBUG [Server b0cdcc59c218f7254a42] delay duration 296.701553ms server | 1.093 DEBUG Setting timeout of 296.701553ms server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] pn=78209890 type=Initial pri-unv-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 125 server | -> RX 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 | 1.127 DEBUG [Server b0cdcc59c218f7254a42] Rx ACK space=in, ranges=[0..=0] server | 1.127 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 1.127 DEBUG on_packets_acked this=0x555c0039eb40, limited=1, bytes_in_flight=804, cwnd=12320, state=SlowStart, new_acked=428 server | 1.127 DEBUG Acked crypto frame space=in offset=0 length=90 server | 1.127 DEBUG [pri-unv-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948] Path validated Instant { tv_sec: 247, tv_nsec: 737045405 } server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] Drop packet number space in server | 1.127 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] pn=78209888 type=Handshake pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 105 server | -> RX 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 | -> RX Crypto { offset: 0, len: 36 } server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] Rx ACK space=hs, ranges=[0..=0] server | 1.127 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.127 DEBUG on_packets_acked this=0x555c0039eb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=804 server | 1.127 DEBUG Acked crypto frame space=hs offset=0 length=747 server | 1.127 DEBUG Read Ok(36) bytes server | 1.127 DEBUG [Agent 0x555c00357710] 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 | 1.127 DEBUG [Server b0cdcc59c218f7254a42] TLS connection complete server | 1.127 DEBUG [pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948] Path validated Instant { tv_sec: 247, tv_nsec: 737045405 } server | 1.127 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.127 DEBUG [Crypto] application read keys installed server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] State change from Handshaking -> Connected server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] State change from Connected -> Confirmed server | 1.127 DEBUG PMTUD started with probe size 1380 server | 1.127 INFO [Server b0cdcc59c218f7254a42] Connection established server | 1.127 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 247, tv_nsec: 737045405 } server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] pn=78209888 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 970 server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [116, 73, 30, 201, 19, 132, 89, 12, 176, 233, 2, 60, 181, 88, 73, 9, 68], stateless_reset_token: [80, 78, 10, 118, 89, 193, 250, 173, 34, 227, 193, 182, 63, 174, 78, 54] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [175, 91, 35, 23, 113, 252, 200, 171, 121, 39, 140, 145, 97, 168, 159, 46, 222], stateless_reset_token: [114, 58, 217, 237, 212, 81, 111, 63, 75, 221, 24, 246, 9, 135, 247, 118] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [250, 47, 112, 23, 255, 118, 46, 25, 209, 98, 100, 124, 77, 72, 195, 29, 40], stateless_reset_token: [46, 151, 113, 148, 111, 102, 177, 128, 24, 77, 119, 60, 238, 14, 230, 169] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [36, 237, 84, 31, 34, 203, 157, 184, 176, 45, 203, 72, 23, 0, 1, 45, 21], stateless_reset_token: [227, 34, 30, 87, 93, 104, 51, 248, 56, 238, 136, 145, 252, 41, 254, 114] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [234, 16, 139, 148, 176, 62, 105, 65, 125, 180, 174, 212, 207, 47, 109, 205, 69], stateless_reset_token: [72, 36, 255, 41, 123, 210, 53, 68, 6, 111, 60, 196, 153, 48, 145, 244] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [74, 83, 207, 213, 253, 156, 40, 111, 24, 60, 205, 195, 7, 173, 214, 17, 198], stateless_reset_token: [238, 94, 33, 221, 7, 177, 120, 237, 181, 19, 192, 250, 101, 154, 228, 175] } server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [68, 184, 56, 245, 64, 151, 115, 82, 64, 51, 16, 205, 111, 239, 78, 77, 218], stateless_reset_token: [201, 255, 84, 246, 168, 186, 230, 185, 90, 11, 24, 102, 172, 107, 143, 76] } server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Padding { len: 660 } server | 1.127 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 247, tv_nsec: 737045405 } server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.127 DEBUG Building Handshake dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) scid Some(CID [10]: 4674c49d8c0c77981654) server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] pn=1 type=Handshake pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 64 server | TX -> Ack { largest_acknowledged: 78209888, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] Drop packet number space hs server | 1.127 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.127 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.127 DEBUG [Server b0cdcc59c218f7254a42] pn=0 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1332 server | TX -> Ack { largest_acknowledged: 78209888, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [224, 226, 218, 54, 165, 165, 43, 24, 134, 86], stateless_reset_token: [188, 178, 97, 11, 223, 201, 73, 55, 10, 17, 171, 162, 189, 72, 87, 245] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [148, 144, 222, 111, 53, 75, 26, 232, 162, 45], stateless_reset_token: [194, 91, 108, 96, 61, 123, 227, 14, 17, 26, 137, 29, 98, 132, 86, 203] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [12, 45, 198, 127, 1, 74, 212, 90, 96, 138], stateless_reset_token: [95, 172, 91, 188, 226, 201, 46, 175, 247, 188, 220, 171, 70, 18, 17, 78] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [222, 90, 208, 78, 196, 178, 81, 100, 111, 97], stateless_reset_token: [90, 158, 127, 225, 188, 106, 216, 92, 172, 181, 0, 134, 235, 7, 67, 92] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [5, 154, 19, 26, 28, 224, 68, 169, 14, 4], stateless_reset_token: [10, 84, 5, 18, 205, 200, 42, 252, 171, 176, 233, 41, 70, 252, 50, 10] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [109, 242, 170, 53, 255, 67, 60, 118, 169, 123], stateless_reset_token: [163, 185, 54, 15, 250, 95, 144, 159, 247, 5, 27, 192, 141, 242, 56, 7] } server | TX -> Padding { len: 1041 } server | 1.128 DEBUG packet_sent this=0x555c0039eb40, pn=0, ps=1268 server | 1.128 DEBUG ECN probing: sent 2 probes server | 1.128 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.128 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.128 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.128 DEBUG [Server b0cdcc59c218f7254a42] pn=1 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1296 } server | 1.128 DEBUG packet_sent this=0x555c0039eb40, pn=1, ps=1332 server | 1.128 DEBUG ECN probing: sent 3 probes server | 1.128 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.128 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.128 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.128 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.30387ms server | 1.128 DEBUG Setting timeout of 1.30387ms server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] send session ticket 407900128e1b7709e3eed761ee16f4250c6d2179bfdc0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a4674c49d8c0c77981654100ab0cdcc59c218f7254a42110c000000011aea7a7a000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.129 DEBUG Path = 'pmsekwglsv' server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.129 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.129 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] delay duration 238.381µs server | 1.129 DEBUG Setting timeout of 238.381µs server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] pn=78209889 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1406 server | -> RX Ping server | -> RX Padding { len: 1374 } server | 1.129 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 247, tv_nsec: 738926313 } server | 1.129 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 247, tv_nsec: 738926313 } server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.129 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] pn=2 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 46 server | TX -> Ack { largest_acknowledged: 78209889, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } server | 1.129 DEBUG ECN probing: sent 4 probes server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.129 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] pn=3 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1195, fin: false } server | 1.129 DEBUG packet_sent this=0x555c0039eb40, pn=3, ps=1232 server | 1.129 DEBUG ECN probing: sent 5 probes server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.129 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.129 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.792487ms server | 1.129 DEBUG Setting timeout of 1.792487ms server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.129 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.129 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.129 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.771548ms server | 1.129 DEBUG Setting timeout of 1.771548ms server | 1.132 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.132 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.132 DEBUG [Server b0cdcc59c218f7254a42] pn=4 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1195, len: >>1193, fin: false } server | 1.132 DEBUG packet_sent this=0x555c0039eb40, pn=4, ps=1232 server | 1.132 DEBUG ECN probing: sent 6 probes server | 1.132 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.132 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.132 DEBUG [Server b0cdcc59c218f7254a42] pn=5 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2388, len: >>1193, fin: false } server | 1.132 DEBUG packet_sent this=0x555c0039eb40, pn=5, ps=1232 server | 1.132 DEBUG ECN probing: sent 7 probes server | 1.132 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.132 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.132 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.132 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.7561ms server | 1.132 DEBUG Setting timeout of 1.7561ms server | 1.132 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.132 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.132 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.132 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.73499ms server | 1.132 DEBUG Setting timeout of 1.73499ms server | 1.135 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.135 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.135 DEBUG [Server b0cdcc59c218f7254a42] pn=6 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3581, len: >>1193, fin: false } server | 1.135 DEBUG packet_sent this=0x555c0039eb40, pn=6, ps=1232 server | 1.135 DEBUG ECN probing: sent 8 probes server | 1.135 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.135 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.135 DEBUG [Server b0cdcc59c218f7254a42] pn=7 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 4774, len: >>1193, fin: false } server | 1.135 DEBUG packet_sent this=0x555c0039eb40, pn=7, ps=1232 server | 1.135 DEBUG ECN probing: sent 9 probes server | 1.135 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.135 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.135 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.135 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.81501ms server | 1.135 DEBUG Setting timeout of 1.81501ms server | 1.135 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.135 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.135 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.135 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.798479ms server | 1.136 DEBUG Setting timeout of 1.798479ms server | 1.138 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.138 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.138 DEBUG [Server b0cdcc59c218f7254a42] pn=8 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 5967, len: >>1193, fin: false } server | 1.138 DEBUG packet_sent this=0x555c0039eb40, pn=8, ps=1232 server | 1.138 DEBUG ECN probing: sent 10 probes server | 1.138 DEBUG ECN probing concluded with 10 probes sent server | 1.138 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.138 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.138 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.138 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.507446ms server | 1.138 DEBUG Setting timeout of 1.507446ms server | 1.138 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.138 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.138 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.138 DEBUG [Server b0cdcc59c218f7254a42] delay duration 1.480005ms server | 1.138 DEBUG Setting timeout of 1.480005ms server | 1.140 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.140 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.140 DEBUG [Server b0cdcc59c218f7254a42] pn=9 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, NotEct) len 1232 server | TX -> Stream { stream_id: 0, offset: 7160, len: >>1193, fin: false } server | 1.140 DEBUG packet_sent this=0x555c0039eb40, pn=9, ps=1232 server | 1.140 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1096, pto: None, probe: EnumSet(), paced: false } server | 1.140 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.140 DEBUG [Server b0cdcc59c218f7254a42] pn=10 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, NotEct) len 1096 server | TX -> Stream { stream_id: 0, offset: 8353, len: >>1057, fin: false } server | 1.140 DEBUG packet_sent this=0x555c0039eb40, pn=10, ps=1096 server | 1.140 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.140 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.140 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.140 DEBUG [Server b0cdcc59c218f7254a42] delay duration 118.678074ms server | 1.140 DEBUG Setting timeout of 118.678074ms server | 1.140 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.140 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.140 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.140 DEBUG [Server b0cdcc59c218f7254a42] delay duration 118.658026ms server | 1.140 DEBUG Setting timeout of 118.658026ms server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] pn=78209890 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1444 server | -> RX Ping server | -> RX Padding { len: 1415 } server | 1.162 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 247, tv_nsec: 771697217 } server | 1.162 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 247, tv_nsec: 771697217 } server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.162 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] pn=11 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, NotEct) len 46 server | TX -> Ack { largest_acknowledged: 78209890, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.162 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.162 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] delay duration 96.982666ms server | 1.162 DEBUG Setting timeout of 96.982666ms server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] pn=78209891 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 39 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 6, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | -> RX Padding { len: 3 } server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] Rx ACK space=ap, ranges=[0..=2] server | 1.162 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 1.162 INFO ECN validation succeeded, path is capable server | 1.162 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9720/14920 ssthresh 18446744073709551615] slow start += 2600 server | 1.162 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9720/14920 ssthresh 18446744073709551615] on_packets_acked this=0x555c0039eb40, limited=0, bytes_in_flight=9720, cwnd=14920, state=SlowStart, new_acked=2600 server | 1.162 DEBUG PMTUD probe of size 1380 succeeded server | 1.162 DEBUG PMTUD started with probe size 1420 server | 1.162 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.162 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.162 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.162 DEBUG CRYPTO for ap offset=0, len=337 server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] pn=12 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 9410, len: 830, fin: true } server | TX -> Crypto { offset: 0, len: 337 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 123, 173, 210, 15, 178, 125, 175, 163, 202, 191, 37, 55, 134, 189, 77, 77, 224, 30, 227, 118, 84, 231, 24, 168, 92, 246, 3, 61, 254, 98, 227, 59, 139, 168, 125, 193] } server | TX -> Padding { len: 114 } server | 1.162 DEBUG packet_sent this=0x555c0039eb40, pn=12, ps=1372 server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.162 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.162 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] delay duration 108.551192ms server | 1.162 DEBUG Setting timeout of 108.551192ms server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.162 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.162 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.162 DEBUG [Server b0cdcc59c218f7254a42] delay duration 108.50764ms server | 1.162 DEBUG Setting timeout of 108.50764ms server | 1.164 DEBUG [Server b0cdcc59c218f7254a42] pn=78209892 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 39 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 5, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | -> RX Padding { len: 3 } server | 1.164 DEBUG [Server b0cdcc59c218f7254a42] Rx ACK space=ap, ranges=[0..=4] server | 1.164 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 1.164 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8628/17384 ssthresh 18446744073709551615] slow start += 2464 server | 1.164 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8628/17384 ssthresh 18446744073709551615] on_packets_acked this=0x555c0039eb40, limited=0, bytes_in_flight=8628, cwnd=17384, state=SlowStart, new_acked=2464 server | 1.164 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.164 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.164 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.164 DEBUG [Server b0cdcc59c218f7254a42] delay duration 99.810832ms server | 1.164 DEBUG Setting timeout of 99.810832ms server | 1.164 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.164 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.164 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.164 DEBUG [Server b0cdcc59c218f7254a42] delay duration 99.749047ms server | 1.164 DEBUG Setting timeout of 99.749047ms server | 1.167 DEBUG [Server b0cdcc59c218f7254a42] pn=78209893 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 39 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 9, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } server | -> RX Padding { len: 3 } server | 1.167 DEBUG [Server b0cdcc59c218f7254a42] Rx ACK space=ap, ranges=[0..=6] server | 1.167 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6 server | 1.167 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6164/19848 ssthresh 18446744073709551615] slow start += 2464 server | 1.167 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6164/19848 ssthresh 18446744073709551615] on_packets_acked this=0x555c0039eb40, limited=0, bytes_in_flight=6164, cwnd=19848, state=SlowStart, new_acked=2464 server | 1.167 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.167 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.167 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.167 DEBUG [Server b0cdcc59c218f7254a42] delay duration 90.420075ms server | 1.167 DEBUG Setting timeout of 90.420075ms server | 1.167 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.167 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.167 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.167 DEBUG [Server b0cdcc59c218f7254a42] delay duration 90.356846ms server | 1.167 DEBUG Setting timeout of 90.356846ms server | 1.169 DEBUG [Server b0cdcc59c218f7254a42] pn=78209894 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 39 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 7, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | -> RX Padding { len: 3 } server | 1.169 DEBUG [Server b0cdcc59c218f7254a42] Rx ACK space=ap, ranges=[0..=8] server | 1.169 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=8 server | 1.169 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3700/22312 ssthresh 18446744073709551615] slow start += 2464 server | 1.169 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3700/22312 ssthresh 18446744073709551615] on_packets_acked this=0x555c0039eb40, limited=0, bytes_in_flight=3700, cwnd=22312, state=SlowStart, new_acked=2464 server | 1.169 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.169 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.169 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.169 DEBUG [Server b0cdcc59c218f7254a42] delay duration 83.109499ms server | 1.169 DEBUG Setting timeout of 83.109499ms server | 1.169 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.169 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.169 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.169 DEBUG [Server b0cdcc59c218f7254a42] delay duration 83.060938ms server | 1.169 DEBUG Setting timeout of 83.060938ms server | 1.172 DEBUG [Server b0cdcc59c218f7254a42] pn=78209895 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 39 server | -> RX Ack { largest_acknowledged: 10, ack_delay: 6, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | -> RX Padding { len: 3 } server | 1.172 DEBUG [Server b0cdcc59c218f7254a42] Rx ACK space=ap, ranges=[0..=10] server | 1.172 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=10 server | 1.172 WARN ECN validation failed, no ECT(0) packets were newly acked server | 1.172 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] slow start += 2328 server | 1.172 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] on_packets_acked this=0x555c0039eb40, limited=0, bytes_in_flight=1372, cwnd=24640, state=SlowStart, new_acked=2328 server | 1.172 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.172 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.172 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.172 DEBUG [Server b0cdcc59c218f7254a42] delay duration 74.699874ms server | 1.172 DEBUG Setting timeout of 74.699874ms server | 1.172 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.172 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.172 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.172 DEBUG [Server b0cdcc59c218f7254a42] delay duration 74.621116ms server | 1.172 DEBUG Setting timeout of 74.621116ms server | 1.194 DEBUG [Server b0cdcc59c218f7254a42] pn=78209896 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, Ect0) len 39 server | -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" } server | -> RX Padding { len: 7 } server | 1.194 INFO [Server b0cdcc59c218f7254a42] ConnectionClose received. Error code: Transport(0) frame type 0 reason server | 1.194 DEBUG [Server b0cdcc59c218f7254a42] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 248, tv_nsec: 59844947 } } server | 1.194 DEBUG [pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948] Path validated Instant { tv_sec: 247, tv_nsec: 803780472 } server | 1.194 DEBUG [Server b0cdcc59c218f7254a42] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.194 DEBUG Building Short dcid Some(CID [17]: af5b24df68c3196b3ef219d86c9c837288) server | 1.194 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 247, tv_nsec: 803780472 } server | 1.194 DEBUG [Server b0cdcc59c218f7254a42] pn=13 type=Short pri-path:af5b24df68c3196b3ef219d86c9c837288 [::]:443->[::ffff:193.167.0.100]:55948 IpTos(Cs0, NotEct) len 50 server | TX -> Ack { largest_acknowledged: 78209896, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" } server | 1.194 DEBUG Setting timeout of 255.905567ms server | 1.194 DEBUG Setting timeout of 255.893034ms 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 10:23:54,457 Using the client's key log file. 2025-04-30 10:23:54,467 2025-04-30 10:23:54,467 Using the client's key log file. 2025-04-30 10:23:54,467 Using the client's key log file. 2025-04-30 10:23:54,476 2025-04-30 10:23:54,477 Using the client's key log file. 2025-04-30 10:23:55,053 Check of downloaded files succeeded. 2025-04-30 10:23:55,623 Check of Retry succeeded. Token used: 52657472790100812adfe25d191f9554ff0c5f41ce306cece3e38bda1ea2f4449fc89617e2f6acd0fe31b105500be13b80ec0e2fa511cbc9c2d384f70d