2025-04-30 12:25:04,727 Generated random file: gnswwuvkyn of size: 10240 2025-04-30 12:25:04,727 Requests: https://server4:443/gnswwuvkyn 2025-04-30 12:25:04,787 2025-04-30 12:25:04,787 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_wkj2woxl/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_9mcvdko6/ DOWNLOADS=/tmp/download_whql6x_m/ SERVER_LOGS=/tmp/logs_server_jss77l2x CLIENT_LOGS=/tmp/logs_client_zwbfonnr SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=litespeedtech/lsquic-qir:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/gnswwuvkyn" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 12:25:16,838 Container sim Created Container server Recreate Container client Recreate Container server Recreated Container client 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-checksumming: off client | tx-checksum-ip-generic: off client | tx-checksum-sctp: off client | tcp-segmentation-offload: off client | tx-tcp-segmentation: off [requested on] client | tx-tcp-ecn-segmentation: off [requested on] client | tx-tcp-mangleid-segmentation: off [requested on] client | tx-tcp6-segmentation: off [requested on] client | tx-udp-segmentation: off [requested on] 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 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.ZbohQKBArd 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.ZbohQKBArd server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.ZbohQKBArd -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 | 05:63:57:88:64:88:f2:47:ed:e3:51:2d:23:13:0b:b3: server | e0:94:b9:35 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 12:25:04 2025 server | Not After : Sat May 10 12:25:04 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:06:19:4d:a9:81:45:eb:ad:d1:b9:0e:e4:3e:0f:55: server | c7:6c:75:73:64:cf:df:eb:da:9d:86:46:36:ef:9c:ad: server | 7a:bf:f9:84:5b:64:d5:ac:1f:63:4b:aa:be:ae:5b:36: server | 04:77:17:81:08:e9:65:40:fd:62:a3:2c:36:e0:65:d1: server | 42 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 | f4:fb:14:b0:f1:70:e2:50:cb:89:b3:df:73:64:0b:02: server | de:de:62:cd server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 4f:e9:a5:be:95:28:b1:41:95:c6:86:3d:1f:bc:0e:55: server | af:54:64:db server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:21:00:e8:67:35:7d:51:57:54:8b:5a:8e:2d: server | 97:f9:9a:d4:5f:67:7e:6a:f2:30:64:ab:d1:26:96:02: server | e3:b0:42:f8:96:02:20:75:d7:51:e3:9b:91:57:24:11: server | 75:e4:23:b7:09:7b:7f:9b:3d:c2:03:b5:9f:a8:d2:36: server | 79:cd:86:df:96:25:3d server | Fingerprint (SHA-256): server | 30:05:F6:AB:AE:FC:1C:E5:FE:56:09:66:3B:F7:55:63:B1:E8:FA:8C:EC:F2:53:FE:BC:CA:2E:52:DB:58:C2:3C server | Fingerprint (SHA1): server | 10:D6:8B:41:B8:E0:85:93:14:BE:86:B7:E7:46:15:C5:FC:68:80:54 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.740 DEBUG [Server] Unsupported version: 57414954 server | 0.740 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:39164 IpTos(Cs0, NotEct) len 15 sim | server:443 is available after 1.004131192s server | 0.740 DEBUG [Server] Unsupported version: 57414954 server | 0.740 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:39164 IpTos(Cs0, NotEct) len 15 server | 0.740 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.740 DEBUG [Server] Unsupported version: 57414954 server | 0.740 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:39164 IpTos(Cs0, NotEct) len 15 server | 0.740 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) 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 client | TEST_PARAMS: client | REQUESTS: 'https://server4:443/gnswwuvkyn' client | paths: -p /gnswwuvkyn client | server: server4 client | port: 443 client | CLIENT_PARAMS: server | 1.000 DEBUG [Server] Handle initial server | 1.000 INFO AddressValidation: no token; validating server | 1.000 INFO [Server] Send retry for CID [11]: c0c562a9c7831554e76f04 server | 1.000 DEBUG [Server] type=Retry path:c0c562a9c7831554e76f04 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, NotEct) len 95 server | 1.032 DEBUG [Server] Handle initial server | 1.032 INFO AddressValidation: valid Retry token for c0c562a9c7831554e76f04 server | 1.032 INFO [Server] Accept connection CID [11]: c0c562a9c7831554e76f04 server | 1.032 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.033 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.033 INFO [Server ...] Received valid Initial packet with scid CID [8]: 8bedaa793b538f16 dcid CID [10]: 194d909d0ccfaa532b43 server | 1.033 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=194d909d0ccfaa532b43 server | 1.033 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.033 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.033 DEBUG [Server 194d909d0ccfaa532b43] State change from Init -> WaitInitial server | 1.033 DEBUG [Server 194d909d0ccfaa532b43] pn=1 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 1200 server | -> RX Crypto { offset: 0, len: 248 } server | -> RX Padding { len: 849 } server | 1.033 DEBUG Read Ok(248) bytes server | 1.033 DEBUG 0-RTT: no token, no 0-RTT server | 1.034 DEBUG Read secret available for Handshake: SymKey [32]: e7295c75e1d62009d1586dc8d888d9f78bbbc13aa204bee18b3861121e9bc095 server | 1.034 DEBUG Write secret available for Handshake: SymKey [32]: 5f1a58a8089304f52a51e38b13247de26001385ae8ff639401fc25ed9be90e0c server | 1.034 DEBUG Writing transport parameters, msg=8 server | 1.034 DEBUG Read secret available for ApplicationData: SymKey [32]: d3c0565875e998c443225fed3d21c321cf0b566a84accbb557132e359adebd89 server | 1.034 DEBUG Write secret available for ApplicationData: SymKey [32]: 0bea8d707308565ad18b21dec586922cdbc3ad8d1122e2ddce03066e72062f4f server | 1.034 DEBUG [Agent 0x564b091ae710] state -> InProgress server | 1.034 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.034 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.034 DEBUG [Crypto] Handshake keys installed server | 1.034 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.034 DEBUG [Crypto] Application write key installed server | 1.034 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 374, tv_nsec: 716769107 } server | 1.034 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:35131] Make permanent server | 1.034 DEBUG [unv-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131] set as primary path server | 1.034 DEBUG [Server 194d909d0ccfaa532b43] State change from WaitInitial -> Handshaking server | 1.034 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.034 DEBUG Building Initial dcid Some(CID [8]: 8bedaa793b538f16) scid Some(CID [10]: 8448b6774b79cd052827) server | 1.034 DEBUG CRYPTO for in offset=0, len=90 server | 1.034 DEBUG [Server 194d909d0ccfaa532b43] pn=0 type=Initial pri-unv-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 147 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 90 } server | 1.034 DEBUG Building Handshake dcid Some(CID [8]: 8bedaa793b538f16) scid Some(CID [10]: 8448b6774b79cd052827) server | 1.034 DEBUG CRYPTO for hs offset=0, len=738 server | 1.034 DEBUG [Server 194d909d0ccfaa532b43] pn=0 type=Handshake pri-unv-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 933 server | TX -> Crypto { offset: 0, len: 738 } server | 1.034 DEBUG packet_sent this=0x564b091f5b40, pn=0, ps=786 server | 1.034 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.034 DEBUG [Server 194d909d0ccfaa532b43] pad Initial from 933 to PLPMTU 1232 server | 1.034 DEBUG packet_sent this=0x564b091f5b40, pn=0, ps=446 server | 1.034 DEBUG ECN probing: sent 1 probes server | 1.034 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.034 DEBUG Building Initial dcid Some(CID [8]: 8bedaa793b538f16) scid Some(CID [10]: 8448b6774b79cd052827) server | 1.035 DEBUG Building Handshake dcid Some(CID [8]: 8bedaa793b538f16) scid Some(CID [10]: 8448b6774b79cd052827) server | 1.035 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.035 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.035 DEBUG [Server 194d909d0ccfaa532b43] delay duration 297.163795ms server | 1.035 DEBUG Setting timeout of 297.163795ms server | 1.035 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.035 DEBUG Building Initial dcid Some(CID [8]: 8bedaa793b538f16) scid Some(CID [10]: 8448b6774b79cd052827) server | 1.035 DEBUG Building Handshake dcid Some(CID [8]: 8bedaa793b538f16) scid Some(CID [10]: 8448b6774b79cd052827) server | 1.035 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.035 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.035 DEBUG [Server 194d909d0ccfaa532b43] delay duration 297.127768ms server | 1.035 DEBUG Setting timeout of 297.127768ms server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] pn=2 type=Initial pri-unv-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 1035 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 201, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Padding { len: 929 } server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] Rx ACK space=in, ranges=[0..=0] server | 1.069 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 1.069 DEBUG on_packets_acked this=0x564b091f5b40, limited=1, bytes_in_flight=786, cwnd=12320, state=SlowStart, new_acked=446 server | 1.069 DEBUG Acked crypto frame space=in offset=0 length=90 server | 1.069 DEBUG [pri-unv-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131] Path validated Instant { tv_sec: 374, tv_nsec: 753758970 } server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] Drop packet number space in server | 1.069 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] pn=3 type=Handshake pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 89 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 92, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Crypto { offset: 0, len: 36 } server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] Rx ACK space=hs, ranges=[0..=0] server | 1.069 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.069 DEBUG on_packets_acked this=0x564b091f5b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=786 server | 1.069 DEBUG Acked crypto frame space=hs offset=0 length=738 server | 1.069 DEBUG Read Ok(36) bytes server | 1.069 DEBUG [Agent 0x564b091ae710] 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.069 DEBUG [Server 194d909d0ccfaa532b43] TLS connection complete server | 1.069 DEBUG [pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131] Path validated Instant { tv_sec: 374, tv_nsec: 753758970 } server | 1.069 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.069 DEBUG [Crypto] application read keys installed server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] State change from Handshaking -> Connected server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] State change from Connected -> Confirmed server | 1.069 DEBUG PMTUD started with probe size 1380 server | 1.069 INFO [Server 194d909d0ccfaa532b43] Connection established server | 1.069 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 374, tv_nsec: 753758970 } server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] pn=4 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 76 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [121, 215, 175, 33, 174, 105, 205, 227], stateless_reset_token: [85, 48, 127, 178, 183, 0, 90, 202, 18, 225, 160, 20, 147, 124, 255, 43] } server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 1.069 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 374, tv_nsec: 753758970 } server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.069 DEBUG Building Handshake dcid Some(CID [8]: 8bedaa793b538f16) scid Some(CID [10]: 8448b6774b79cd052827) server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] pn=1 type=Handshake pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 52 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] Drop packet number space hs server | 1.069 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.069 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] pn=0 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1332 server | TX -> Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [185, 247, 81, 233, 203, 107, 26, 199, 24, 148], stateless_reset_token: [52, 31, 99, 146, 194, 124, 199, 89, 245, 45, 38, 21, 16, 142, 49, 32] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [134, 129, 28, 189, 43, 61, 36, 173, 73, 185], stateless_reset_token: [32, 172, 230, 33, 205, 197, 148, 39, 1, 4, 60, 177, 118, 64, 46, 3] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [138, 58, 48, 86, 189, 58, 228, 6, 180, 229], stateless_reset_token: [163, 113, 49, 172, 196, 18, 83, 10, 64, 143, 35, 187, 218, 220, 118, 18] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [236, 199, 0, 47, 49, 78, 0, 140, 99, 253], stateless_reset_token: [148, 121, 17, 187, 14, 47, 0, 16, 184, 121, 134, 2, 124, 94, 172, 189] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [65, 212, 87, 193, 156, 43, 129, 12, 225, 196], stateless_reset_token: [82, 249, 208, 34, 162, 141, 134, 216, 190, 122, 196, 189, 225, 104, 247, 122] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [106, 47, 27, 158, 188, 103, 41, 125, 158, 179], stateless_reset_token: [19, 208, 224, 216, 28, 61, 213, 47, 24, 133, 24, 161, 54, 254, 123, 90] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [214, 225, 53, 21, 55, 179, 19, 77, 245, 113], stateless_reset_token: [127, 56, 242, 135, 107, 217, 237, 180, 33, 206, 251, 93, 146, 152, 50, 12] } server | TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10000, ignore_order: false } server | TX -> Padding { len: 1028 } server | 1.069 DEBUG packet_sent this=0x564b091f5b40, pn=0, ps=1280 server | 1.069 DEBUG ECN probing: sent 2 probes server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.069 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.069 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] pn=1 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 1.069 DEBUG packet_sent this=0x564b091f5b40, pn=1, ps=1332 server | 1.069 DEBUG ECN probing: sent 3 probes server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.069 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.069 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.069 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.548259ms server | 1.069 DEBUG Setting timeout of 1.548259ms server | 1.070 DEBUG [Server 194d909d0ccfaa532b43] send session ticket 4072000bc0c562a9c7831554e76f040104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a8448b6774b79cd052827100a194d909d0ccfaa532b43110c00000001caaa4a0a000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.070 DEBUG Path = 'gnswwuvkyn' server | 1.070 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.070 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.070 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.070 DEBUG [Server 194d909d0ccfaa532b43] delay duration 563.858µs server | 1.070 DEBUG Setting timeout of 563.858µs server | 1.072 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.072 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.072 DEBUG [Server 194d909d0ccfaa532b43] pn=2 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 1.072 DEBUG packet_sent this=0x564b091f5b40, pn=2, ps=1232 server | 1.072 DEBUG ECN probing: sent 4 probes server | 1.072 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.072 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.072 DEBUG [Server 194d909d0ccfaa532b43] pn=3 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 1.072 DEBUG packet_sent this=0x564b091f5b40, pn=3, ps=1232 server | 1.072 DEBUG ECN probing: sent 5 probes server | 1.072 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.072 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.072 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.072 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.783309ms server | 1.072 DEBUG Setting timeout of 1.783309ms server | 1.072 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.072 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.072 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.072 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.766939ms server | 1.072 DEBUG Setting timeout of 1.766939ms server | 1.074 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.074 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.075 DEBUG [Server 194d909d0ccfaa532b43] pn=4 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false } server | 1.075 DEBUG packet_sent this=0x564b091f5b40, pn=4, ps=1232 server | 1.075 DEBUG ECN probing: sent 6 probes server | 1.075 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.075 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.075 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.075 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.441753ms server | 1.075 DEBUG Setting timeout of 1.441753ms server | 1.075 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.075 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.075 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.075 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.419171ms server | 1.075 DEBUG Setting timeout of 1.419171ms server | 1.077 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.077 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.077 DEBUG [Server 194d909d0ccfaa532b43] pn=5 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false } server | 1.077 DEBUG packet_sent this=0x564b091f5b40, pn=5, ps=1232 server | 1.077 DEBUG ECN probing: sent 7 probes server | 1.077 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.077 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.077 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.077 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.052803ms server | 1.077 DEBUG Setting timeout of 1.052803ms server | 1.077 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.077 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.077 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.077 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.032174ms server | 1.077 DEBUG Setting timeout of 1.032174ms server | 1.079 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.079 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.079 DEBUG [Server 194d909d0ccfaa532b43] pn=6 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 4810, len: >>1202, fin: false } server | 1.079 DEBUG packet_sent this=0x564b091f5b40, pn=6, ps=1232 server | 1.079 DEBUG ECN probing: sent 8 probes server | 1.079 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.079 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.079 DEBUG [Server 194d909d0ccfaa532b43] pn=7 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 6012, len: >>1202, fin: false } server | 1.079 DEBUG packet_sent this=0x564b091f5b40, pn=7, ps=1232 server | 1.079 DEBUG ECN probing: sent 9 probes server | 1.079 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.079 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.079 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.079 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.764434ms server | 1.079 DEBUG Setting timeout of 1.764434ms server | 1.079 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.079 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.079 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.079 DEBUG [Server 194d909d0ccfaa532b43] delay duration 1.743585ms server | 1.079 DEBUG Setting timeout of 1.743585ms server | 1.082 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.082 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.082 DEBUG [Server 194d909d0ccfaa532b43] pn=8 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 7214, len: >>1202, fin: false } server | 1.082 DEBUG packet_sent this=0x564b091f5b40, pn=8, ps=1232 server | 1.082 DEBUG ECN probing: sent 10 probes server | 1.082 DEBUG ECN probing concluded with 10 probes sent server | 1.082 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1084, pto: None, probe: EnumSet(), paced: false } server | 1.082 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.082 DEBUG [Server 194d909d0ccfaa532b43] pn=9 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, NotEct) len 1084 server | TX -> Stream { stream_id: 0, offset: 8416, len: >>1054, fin: false } server | 1.082 DEBUG packet_sent this=0x564b091f5b40, pn=9, ps=1084 server | 1.082 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.082 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.082 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.082 DEBUG [Server 194d909d0ccfaa532b43] delay duration 117.369198ms server | 1.082 DEBUG Setting timeout of 117.369198ms server | 1.082 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.082 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.082 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.082 DEBUG [Server 194d909d0ccfaa532b43] delay duration 117.347688ms server | 1.082 DEBUG Setting timeout of 117.347688ms server | 1.102 DEBUG [Server 194d909d0ccfaa532b43] pn=5 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 34 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 5, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.102 DEBUG [Server 194d909d0ccfaa532b43] Rx ACK space=ap, ranges=[0..=1] server | 1.102 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 1.102 WARN ECN validation failed, no ECN counts in ACK frame server | 1.102 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9708/14932 ssthresh 18446744073709551615] slow start += 2612 server | 1.102 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9708/14932 ssthresh 18446744073709551615] on_packets_acked this=0x564b091f5b40, limited=0, bytes_in_flight=9708, cwnd=14932, state=SlowStart, new_acked=2612 server | 1.102 DEBUG PMTUD probe of size 1380 succeeded server | 1.102 DEBUG PMTUD started with probe size 1420 server | 1.102 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.102 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.102 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.102 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.102 DEBUG CRYPTO for ap offset=0, len=321 server | 1.102 DEBUG [Server 194d909d0ccfaa532b43] pn=10 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, NotEct) len 1372 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 9470, len: 770, fin: true } server | TX -> Crypto { offset: 0, len: 321 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 37, 88, 101, 136, 158, 160, 235, 109, 7, 62, 105, 192, 227, 26, 48, 139, 132, 222, 41, 1, 17, 210, 2, 21, 106, 20, 250, 211, 16, 169, 54, 108, 60, 141, 97, 138] } server | TX -> Padding { len: 199 } server | 1.102 DEBUG packet_sent this=0x564b091f5b40, pn=10, ps=1372 server | 1.102 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.102 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.102 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.102 DEBUG [Server 194d909d0ccfaa532b43] delay duration 92.057218ms server | 1.102 DEBUG Setting timeout of 92.057218ms server | 1.102 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.102 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.102 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.102 DEBUG [Server 194d909d0ccfaa532b43] delay duration 92.023585ms server | 1.102 DEBUG Setting timeout of 92.023585ms server | 1.105 DEBUG [Server 194d909d0ccfaa532b43] pn=6 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 34 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 7, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 1.105 DEBUG [Server 194d909d0ccfaa532b43] Rx ACK space=ap, ranges=[0..=3] server | 1.105 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3 server | 1.105 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8616/17396 ssthresh 18446744073709551615] slow start += 2464 server | 1.105 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8616/17396 ssthresh 18446744073709551615] on_packets_acked this=0x564b091f5b40, limited=0, bytes_in_flight=8616, cwnd=17396, state=SlowStart, new_acked=2464 server | 1.105 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.105 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.105 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.105 DEBUG [Server 194d909d0ccfaa532b43] delay duration 81.435536ms server | 1.105 DEBUG Setting timeout of 81.435536ms server | 1.105 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.105 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.105 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.105 DEBUG [Server 194d909d0ccfaa532b43] delay duration 81.376065ms server | 1.105 DEBUG Setting timeout of 81.376065ms server | 1.108 DEBUG [Server 194d909d0ccfaa532b43] pn=7 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 34 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 7, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 1.108 DEBUG [Server 194d909d0ccfaa532b43] Rx ACK space=ap, ranges=[0..=5] server | 1.108 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5 server | 1.108 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6152/19860 ssthresh 18446744073709551615] slow start += 2464 server | 1.108 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6152/19860 ssthresh 18446744073709551615] on_packets_acked this=0x564b091f5b40, limited=0, bytes_in_flight=6152, cwnd=19860, state=SlowStart, new_acked=2464 server | 1.108 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.108 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.108 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.108 DEBUG [Server 194d909d0ccfaa532b43] delay duration 72.143048ms server | 1.108 DEBUG Setting timeout of 72.143048ms server | 1.108 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.108 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.108 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.108 DEBUG [Server 194d909d0ccfaa532b43] delay duration 72.098435ms server | 1.108 DEBUG Setting timeout of 72.098435ms server | 1.111 DEBUG [Server 194d909d0ccfaa532b43] pn=8 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 34 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 5, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | 1.111 DEBUG [Server 194d909d0ccfaa532b43] Rx ACK space=ap, ranges=[0..=7] server | 1.111 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7 server | 1.111 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3688/22324 ssthresh 18446744073709551615] slow start += 2464 server | 1.111 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3688/22324 ssthresh 18446744073709551615] on_packets_acked this=0x564b091f5b40, limited=0, bytes_in_flight=3688, cwnd=22324, state=SlowStart, new_acked=2464 server | 1.111 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.111 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.112 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.112 DEBUG [Server 194d909d0ccfaa532b43] delay duration 63.385111ms server | 1.112 DEBUG Setting timeout of 63.385111ms server | 1.112 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.112 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.112 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.112 DEBUG [Server 194d909d0ccfaa532b43] delay duration 63.342731ms server | 1.112 DEBUG Setting timeout of 63.342731ms server | 1.115 DEBUG [Server 194d909d0ccfaa532b43] pn=9 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 34 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 6, first_ack_range: 9, ack_ranges: [], ecn_count: None } server | 1.115 DEBUG [Server 194d909d0ccfaa532b43] Rx ACK space=ap, ranges=[0..=9] server | 1.115 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=9 server | 1.115 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] slow start += 2316 server | 1.115 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] on_packets_acked this=0x564b091f5b40, limited=0, bytes_in_flight=1372, cwnd=24640, state=SlowStart, new_acked=2316 server | 1.115 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.115 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.115 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.115 DEBUG [Server 194d909d0ccfaa532b43] delay duration 55.602845ms server | 1.115 DEBUG Setting timeout of 55.602845ms server | 1.115 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.115 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.115 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.115 DEBUG [Server 194d909d0ccfaa532b43] delay duration 55.550807ms server | 1.115 DEBUG Setting timeout of 55.550807ms client | /logs/8BEDAA793B538F16.keys client | /logs/keys.log exists server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] pn=10 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 34 server | -> RX Ack { largest_acknowledged: 10, ack_delay: 13, first_ack_range: 10, ack_ranges: [], ecn_count: None } server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] Rx ACK space=ap, ranges=[0..=10] server | 1.134 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=10 server | 1.134 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/26012 ssthresh 18446744073709551615] slow start += 1372 server | 1.134 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/26012 ssthresh 18446744073709551615] on_packets_acked this=0x564b091f5b40, limited=0, bytes_in_flight=0, cwnd=26012, state=SlowStart, new_acked=1372 server | 1.134 DEBUG PMTUD probe of size 1420 succeeded server | 1.134 DEBUG PMTUD started with probe size 1470 server | 1.134 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 1.134 DEBUG Acked crypto frame space=ap offset=0 length=321 server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.134 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.134 DEBUG Sending PMTUD probe of size 1470, count 1 server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] pn=11 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, NotEct) len 1422 server | TX -> Ping server | TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 } server | TX -> Padding { len: 1393 } server | 1.134 DEBUG packet_sent this=0x564b091f5b40, pn=11, ps=1422 server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.134 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.134 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] delay duration 64.571756ms server | 1.134 DEBUG Setting timeout of 64.571756ms server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] pn=11 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, Ect1) len 32 server | -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" } server | 1.134 INFO [Server 194d909d0ccfaa532b43] ConnectionClose received. Error code: Transport(0) frame type 0 reason server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 375, tv_nsec: 13970895 } } server | 1.134 DEBUG [pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131] Path validated Instant { tv_sec: 374, tv_nsec: 819417358 } server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.134 DEBUG Building Short dcid Some(CID [8]: 8bedaa793b538f16) server | 1.134 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 374, tv_nsec: 819417358 } server | 1.134 DEBUG [Server 194d909d0ccfaa532b43] pn=12 type=Short pri-path:8bedaa793b538f16 [::]:443->[::ffff:193.167.0.100]:35131 IpTos(Cs0, NotEct) len 38 server | TX -> Ack { largest_acknowledged: 11, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 8, Ect0: 0, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" } server | 1.134 DEBUG Setting timeout of 194.431499ms server | 1.134 DEBUG Setting timeout of 194.423334ms 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 12:25:16,934 Using the client's key log file. 2025-04-30 12:25:16,943 2025-04-30 12:25:16,944 Using the client's key log file. 2025-04-30 12:25:16,944 Using the client's key log file. 2025-04-30 12:25:16,952 2025-04-30 12:25:16,952 Using the client's key log file. 2025-04-30 12:25:17,514 Check of downloaded files succeeded. 2025-04-30 12:25:18,074 Check of Retry succeeded. Token used: 52657472790100c7b621124f2a46c4343cf8bd663784c7c84fe0149d61d9cf09b160d3415a8e901ed0316c443dec68b9a665f08d557e