2025-04-30 12:25:21,704 Generated random file: jukuttqeai of size: 5120 2025-04-30 12:25:21,704 Generated random file: naiwwkzkdc of size: 10240 2025-04-30 12:25:21,704 Requests: https://server4:443/jukuttqeai https://server4:443/naiwwkzkdc 2025-04-30 12:25:21,762 2025-04-30 12:25:21,763 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_4q6canor/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_fzfjii9k/ DOWNLOADS=/tmp/download_vbb_ypxg/ SERVER_LOGS=/tmp/logs_server_0qrjw_sg CLIENT_LOGS=/tmp/logs_client_1zbtbk8a SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=aiortc/aioquic-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/jukuttqeai https://server4:443/naiwwkzkdc" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 12:25:34,889 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... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Setting up routes... 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 resumption ']' server | + '[' -n /logs/qlog/ ']' server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert 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] server | ++ mktemp server | + P12CERT=/tmp/tmp.Yrz3cdKi4O server | + mkdir -p /neqo/db client | Endpoint's IPv4 address is 193.167.0.100 server | + certutil -N -d sql:/neqo/db --empty-password client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.Yrz3cdKi4O server | Warning: output encryption option -nodes ignored with -export client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | + pk12util -d sql:/neqo/db -i /tmp/tmp.Yrz3cdKi4O -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 | 5d:dd:c4:61:1a:a6:83:43:33:a7:2a:cf:80:b2:d3:80: server | 04:05:de:4c 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:21 2025 server | Not After : Sat May 10 12:25:21 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:cf:b9:59:f1:06:c8:24:5b:18:dd:5f:f5:f0:29:81: server | 42:3d:c7:0f:4d:07:a9:dd:bf:31:51:8c:e3:c1:8f:9c: server | a1:f5:21:66:60:33:6b:dd:f6:ab:fa:b8:0a:58:48:ce: server | e8:d4:e8:e2:29:6d:53:49:17:70:a0:60:c4:b4:e1:f6: server | 70 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 | e3:e7:2f:dd:ca:8c:ce:43:0f:80:9c:4c:a8:9e:24:fd: server | 9f:4d:cb:d4 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | cc:c0:3e:ac:bb:3a:81:b4:a7:90:06:fc:c5:c9:e9:8c: server | 66:3a:f9:c7 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:21:00:cc:61:b1:a6:a4:34:13:6d:e1:8c:d4: server | c7:ef:96:44:71:b5:3d:ee:4a:ac:9c:88:09:a1:e1:4f: server | 4d:78:80:bb:62:02:20:3c:13:3f:dd:ab:d3:72:5d:9f: server | 0a:92:a3:11:d9:0d:ff:b3:7b:f2:2f:ea:a9:9b:ca:d7: server | e3:4a:86:ea:ff:6c:d9 server | Fingerprint (SHA-256): server | BA:A7:F0:44:8D:03:BA:37:69:27:91:DD:1B:12:0D:09:AB:19:5E:31:F8:A5:A6:86:EF:8B:6F:20:C1:D6:D8:6C server | Fingerprint (SHA1): server | 6E:84:4A:A5:63:EF:36:07:6F:7F:F1:4E:CB:06:6A:B6:59:13:F3:A3 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 | + '[' resumption = connectionmigration ']' server | + RUST_LOG=debug server | + RUST_BACKTRACE=1 server | + neqo-server --cc cubic --qns-test resumption --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.818 DEBUG [Server] Unsupported version: 57414954 server | 0.818 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52329 IpTos(Cs0, NotEct) len 15 server | 0.818 DEBUG [Server] Unsupported version: 57414954 server | 0.818 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52329 IpTos(Cs0, NotEct) len 15 server | 0.818 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.818 DEBUG [Server] Unsupported version: 57414954 sim | server:443 is available after 1.056946517s server | 0.818 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52329 IpTos(Cs0, NotEct) len 15 server | 0.818 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: tcpdump: listening on eth1listening on eth0, link-type EN10MB (Ethernet), link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | , snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | Starting client server | 1.183 DEBUG [Server] Handle initial server | 1.183 INFO AddressValidation: no token; accepting server | 1.183 INFO [Server] Accept connection CID [8]: a4bdb9541dd710b5 server | 1.183 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.184 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.184 INFO [Server ...] Received valid Initial packet with scid CID [8]: f16a9171f671c53b dcid CID [8]: a4bdb9541dd710b5 server | 1.184 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=a4bdb9541dd710b5 server | 1.184 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.184 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.184 DEBUG [Server a4bdb9541dd710b5] State change from Init -> WaitInitial server | 1.184 DEBUG [Server a4bdb9541dd710b5] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 530 server | -> RX Crypto { offset: 0, len: 482 } server | 1.185 DEBUG Read Ok(482) bytes server | 1.185 DEBUG 0-RTT: no token, no 0-RTT server | 1.185 DEBUG Read secret available for Handshake: SymKey [32]: bfb9bfe33b507ac395d31744b9351dd22100239992aa8e398c555d51f4dffc35 server | 1.185 DEBUG Write secret available for Handshake: SymKey [32]: 25f203053e3ef09da345c4d236aa28949be98d93ff0604158c8d5c82b614d6d2 server | 1.185 DEBUG Writing transport parameters, msg=8 server | 1.185 DEBUG Read secret available for ApplicationData: SymKey [32]: ae7535971bd6e1996a403a4208cac9daaa2766ba8ec5c01d1543d26a8b737e6c server | 1.185 DEBUG Write secret available for ApplicationData: SymKey [32]: b40b33ee6ad9e9f1e03e9d30805fcbe520653a8ec0173f31e4375778e841f11a server | 1.185 DEBUG [Agent 0x564290dd91d0] state -> InProgress server | 1.185 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.185 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.186 DEBUG [Crypto] Handshake keys installed server | 1.186 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.186 DEBUG [Crypto] Application write key installed server | 1.186 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 565, tv_nsec: 609035762 } server | 1.186 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:60470] Make permanent server | 1.186 DEBUG [unv-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470] set as primary path server | 1.186 DEBUG [Server a4bdb9541dd710b5] State change from WaitInitial -> Handshaking server | 1.186 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1 server | 1.186 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.186 DEBUG Building Initial dcid Some(CID [8]: f16a9171f671c53b) scid Some(CID [10]: 371692601e4fe1a36862) server | 1.186 DEBUG CRYPTO for in offset=0, len=90 server | 1.186 DEBUG [Server a4bdb9541dd710b5] pn=0 type=Initial pri-unv-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 144 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 1.186 DEBUG Building Handshake dcid Some(CID [8]: f16a9171f671c53b) scid Some(CID [10]: 371692601e4fe1a36862) server | 1.186 DEBUG CRYPTO for hs offset=0, len=723 server | 1.186 DEBUG [Server a4bdb9541dd710b5] pn=0 type=Handshake pri-unv-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 915 server | TX -> Crypto { offset: 0, len: 723 } server | 1.186 DEBUG packet_sent this=0x564290dd8b40, pn=0, ps=771 server | 1.186 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.186 DEBUG [Server a4bdb9541dd710b5] pad Initial from 915 to PLPMTU 1232 server | 1.186 DEBUG packet_sent this=0x564290dd8b40, pn=0, ps=461 server | 1.186 DEBUG ECN probing: sent 1 probes server | 1.186 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.186 DEBUG Building Initial dcid Some(CID [8]: f16a9171f671c53b) scid Some(CID [10]: 371692601e4fe1a36862) server | 1.186 DEBUG Building Handshake dcid Some(CID [8]: f16a9171f671c53b) scid Some(CID [10]: 371692601e4fe1a36862) server | 1.186 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.186 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.186 DEBUG [Server a4bdb9541dd710b5] delay duration 296.88476ms server | 1.186 DEBUG Setting timeout of 296.88476ms server | 1.186 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.186 DEBUG Building Initial dcid Some(CID [8]: f16a9171f671c53b) scid Some(CID [10]: 371692601e4fe1a36862) server | 1.186 DEBUG Building Handshake dcid Some(CID [8]: f16a9171f671c53b) scid Some(CID [10]: 371692601e4fe1a36862) server | 1.186 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.186 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.186 DEBUG [Server a4bdb9541dd710b5] delay duration 296.847591ms server | 1.186 DEBUG Setting timeout of 296.847591ms server | 1.221 DEBUG [Server a4bdb9541dd710b5] pn=1 type=Initial pri-unv-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 247, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.221 DEBUG [Server a4bdb9541dd710b5] Rx ACK space=in, ranges=[0..=0] server | 1.221 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 1.221 DEBUG on_packets_acked this=0x564290dd8b40, limited=1, bytes_in_flight=771, cwnd=12320, state=SlowStart, new_acked=461 server | 1.221 DEBUG Acked crypto frame space=in offset=0 length=90 server | 1.221 DEBUG [pri-unv-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470] Path validated Instant { tv_sec: 565, tv_nsec: 646929662 } server | 1.221 DEBUG [Server a4bdb9541dd710b5] Drop packet number space in server | 1.221 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.221 DEBUG [Server a4bdb9541dd710b5] pn=2 type=Handshake pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 91 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 247, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Crypto { offset: 0, len: 36 } server | 1.221 DEBUG [Server a4bdb9541dd710b5] Rx ACK space=hs, ranges=[0..=0] server | 1.221 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.221 DEBUG on_packets_acked this=0x564290dd8b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=771 server | 1.221 DEBUG Acked crypto frame space=hs offset=0 length=723 server | 1.221 DEBUG Read Ok(36) bytes server | 1.221 DEBUG [Agent 0x564290dd91d0] 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.221 DEBUG [Server a4bdb9541dd710b5] TLS connection complete server | 1.221 DEBUG [pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470] Path validated Instant { tv_sec: 565, tv_nsec: 646929662 } server | 1.221 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.221 DEBUG [Crypto] application read keys installed server | 1.221 DEBUG [Server a4bdb9541dd710b5] State change from Handshaking -> Connected server | 1.221 DEBUG [Server a4bdb9541dd710b5] State change from Connected -> Confirmed server | 1.221 DEBUG PMTUD started with probe size 1380 server | 1.221 INFO [Server a4bdb9541dd710b5] Connection established server | 1.221 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 565, tv_nsec: 646929662 } server | 1.221 DEBUG [Server a4bdb9541dd710b5] pn=3 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 1057 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [209, 90, 32, 240, 167, 150, 41, 125], stateless_reset_token: [181, 10, 230, 13, 143, 46, 54, 202, 28, 42, 21, 25, 189, 75, 196, 163] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [229, 116, 38, 69, 48, 34, 253, 176], stateless_reset_token: [249, 67, 146, 131, 100, 55, 5, 13, 129, 132, 40, 200, 195, 125, 242, 49] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [0, 28, 153, 87, 143, 28, 97, 238], stateless_reset_token: [236, 38, 105, 39, 156, 136, 120, 74, 47, 2, 6, 115, 181, 230, 161, 192] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [202, 237, 239, 167, 91, 30, 194, 40], stateless_reset_token: [197, 241, 161, 238, 200, 209, 68, 162, 250, 240, 19, 68, 163, 165, 228, 64] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [17, 158, 169, 107, 108, 108, 235, 16], stateless_reset_token: [19, 249, 75, 135, 54, 235, 95, 147, 155, 15, 195, 28, 109, 20, 100, 108] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [133, 25, 18, 90, 241, 151, 77, 4], stateless_reset_token: [154, 87, 156, 172, 60, 78, 13, 39, 55, 239, 190, 38, 13, 57, 132, 239] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [207, 167, 55, 125, 178, 79, 130, 55], stateless_reset_token: [141, 181, 52, 88, 160, 18, 233, 219, 165, 25, 0, 29, 165, 249, 80, 169] } server | -> RX Padding { len: 832 } server | 1.221 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 565, tv_nsec: 646929662 } server | 1.221 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.221 DEBUG Building Handshake dcid Some(CID [8]: f16a9171f671c53b) scid Some(CID [10]: 371692601e4fe1a36862) server | 1.221 DEBUG [Server a4bdb9541dd710b5] pn=1 type=Handshake pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 49 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.221 DEBUG [Server a4bdb9541dd710b5] Drop packet number space hs server | 1.221 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.221 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.221 DEBUG [Server a4bdb9541dd710b5] pn=0 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 1332 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [2, 47, 102, 228, 93, 74, 254, 52, 157, 134], stateless_reset_token: [149, 5, 222, 90, 53, 220, 40, 140, 229, 27, 178, 17, 234, 179, 52, 179] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [20, 78, 140, 217, 64, 53, 112, 106, 42, 33], stateless_reset_token: [116, 91, 239, 86, 188, 101, 253, 158, 155, 132, 250, 83, 126, 250, 1, 25] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [13, 213, 189, 178, 45, 117, 184, 138, 77, 251], stateless_reset_token: [34, 115, 251, 100, 197, 37, 40, 9, 96, 132, 159, 167, 101, 26, 188, 55] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [74, 13, 229, 97, 231, 173, 19, 46, 229, 215], stateless_reset_token: [123, 162, 236, 154, 148, 77, 103, 183, 130, 88, 180, 52, 51, 245, 163, 167] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [201, 116, 35, 7, 69, 22, 244, 33, 185, 185], stateless_reset_token: [90, 83, 166, 93, 100, 78, 62, 246, 132, 148, 195, 67, 116, 44, 174, 67] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [144, 194, 72, 9, 104, 238, 95, 24, 97, 0], stateless_reset_token: [44, 93, 114, 51, 114, 221, 168, 56, 178, 55, 111, 197, 141, 184, 109, 227] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [153, 195, 9, 127, 147, 134, 165, 98, 197, 218], stateless_reset_token: [191, 210, 57, 211, 57, 26, 64, 117, 213, 100, 132, 231, 132, 218, 247, 185] } server | TX -> Padding { len: 1041 } server | 1.221 DEBUG packet_sent this=0x564290dd8b40, pn=0, ps=1283 server | 1.221 DEBUG ECN probing: sent 2 probes server | 1.221 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.221 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.221 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.221 DEBUG [Server a4bdb9541dd710b5] pn=1 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 1.221 DEBUG packet_sent this=0x564290dd8b40, pn=1, ps=1332 server | 1.221 DEBUG ECN probing: sent 3 probes server | 1.221 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.221 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.221 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.221 DEBUG [Server a4bdb9541dd710b5] delay duration 1.365304ms server | 1.221 DEBUG Setting timeout of 1.365304ms server | 1.221 DEBUG [Server a4bdb9541dd710b5] pn=4 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 50 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 1.221 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 565, tv_nsec: 667726445 } server | 1.221 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.221 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.221 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.221 DEBUG [Server a4bdb9541dd710b5] delay duration 1.33087ms server | 1.221 DEBUG Setting timeout of 1.33087ms server | 1.222 DEBUG [Server a4bdb9541dd710b5] send session ticket 40630008a4bdb9541dd710b50104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a371692601e4fe1a36862110c000000010a1a4aea000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.222 DEBUG Path = 'jukuttqeai' server | 1.222 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.222 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.222 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.222 DEBUG [Server a4bdb9541dd710b5] delay duration 265.656µs server | 1.222 DEBUG Setting timeout of 265.656µs server | 1.224 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.224 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.224 DEBUG [Server a4bdb9541dd710b5] pn=2 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 1.224 DEBUG packet_sent this=0x564290dd8b40, pn=2, ps=1232 server | 1.224 DEBUG ECN probing: sent 4 probes server | 1.224 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.224 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.224 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.224 DEBUG [Server a4bdb9541dd710b5] delay duration 1.004306ms server | 1.224 DEBUG Setting timeout of 1.004306ms server | 1.224 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.224 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.224 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.224 DEBUG [Server a4bdb9541dd710b5] delay duration 981.834µs server | 1.224 DEBUG Setting timeout of 981.834µs server | 1.226 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.226 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.226 DEBUG [Server a4bdb9541dd710b5] pn=3 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 1.226 DEBUG packet_sent this=0x564290dd8b40, pn=3, ps=1232 server | 1.226 DEBUG ECN probing: sent 5 probes server | 1.226 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.226 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.226 DEBUG [Server a4bdb9541dd710b5] pn=4 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false } server | 1.226 DEBUG packet_sent this=0x564290dd8b40, pn=4, ps=1232 server | 1.226 DEBUG ECN probing: sent 6 probes server | 1.226 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.226 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.226 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.226 DEBUG [Server a4bdb9541dd710b5] delay duration 1.79131ms server | 1.226 DEBUG Setting timeout of 1.79131ms server | 1.226 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.226 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.226 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.226 DEBUG [Server a4bdb9541dd710b5] delay duration 1.77028ms server | 1.226 DEBUG Setting timeout of 1.77028ms server | 1.229 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.229 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.229 DEBUG [Server a4bdb9541dd710b5] pn=5 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false } server | 1.229 DEBUG packet_sent this=0x564290dd8b40, pn=5, ps=1232 server | 1.229 DEBUG ECN probing: sent 7 probes server | 1.229 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.229 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.229 DEBUG CRYPTO for ap offset=0, len=305 server | 1.229 DEBUG [Server a4bdb9541dd710b5] pn=6 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 696 server | TX -> Stream { stream_id: 0, offset: 4810, len: 310, fin: true } server | TX -> Crypto { offset: 0, len: 305 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 124, 247, 53, 11, 215, 132, 242, 166, 48, 130, 136, 67, 54, 46, 222, 23, 19, 83, 24, 214, 230, 45, 223, 151, 83, 70, 254, 250, 173, 144, 51, 235, 143, 63, 23, 39] } server | 1.229 DEBUG packet_sent this=0x564290dd8b40, pn=6, ps=696 server | 1.229 DEBUG ECN probing: sent 8 probes server | 1.229 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.229 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.229 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.229 DEBUG [Server a4bdb9541dd710b5] delay duration 1.473321ms server | 1.229 DEBUG Setting timeout of 1.473321ms server | 1.229 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.229 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.229 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.229 DEBUG [Server a4bdb9541dd710b5] delay duration 1.457792ms server | 1.229 DEBUG Setting timeout of 1.457792ms server | 1.232 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.232 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.232 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.232 DEBUG [Server a4bdb9541dd710b5] delay duration 9.088472ms server | 1.232 DEBUG Setting timeout of 9.088472ms server | 1.232 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.232 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.232 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.232 DEBUG [Server a4bdb9541dd710b5] delay duration 9.063675ms server | 1.232 DEBUG Setting timeout of 9.063675ms server | 1.242 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.242 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.242 DEBUG [Server a4bdb9541dd710b5] pn=7 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 32 server | TX -> Ack { largest_acknowledged: 4, ack_delay: 2627, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.242 DEBUG ECN probing: sent 9 probes server | 1.242 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.242 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.242 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.243 DEBUG [Server a4bdb9541dd710b5] delay duration 106.391346ms server | 1.243 DEBUG Setting timeout of 106.391346ms server | 1.243 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.243 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.243 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.243 DEBUG [Server a4bdb9541dd710b5] delay duration 106.368453ms server | 1.243 DEBUG Setting timeout of 106.368453ms server | 1.254 DEBUG [Server a4bdb9541dd710b5] pn=5 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 34 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 18, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.254 DEBUG [Server a4bdb9541dd710b5] Rx ACK space=ap, ranges=[0..=1] server | 1.254 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 1.254 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5624/14935 ssthresh 18446744073709551615] slow start += 2615 server | 1.254 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5624/14935 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=5624, cwnd=14935, state=SlowStart, new_acked=2615 server | 1.254 DEBUG PMTUD probe of size 1380 succeeded server | 1.254 DEBUG PMTUD started with probe size 1420 server | 1.254 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.254 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.254 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.254 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.254 DEBUG [Server a4bdb9541dd710b5] pn=8 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1345 } server | 1.254 DEBUG packet_sent this=0x564290dd8b40, pn=8, ps=1372 server | 1.254 DEBUG ECN probing: sent 10 probes server | 1.254 DEBUG ECN probing concluded with 10 probes sent server | 1.254 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.254 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.254 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.254 DEBUG [Server a4bdb9541dd710b5] delay duration 109.862803ms server | 1.254 DEBUG Setting timeout of 109.862803ms server | 1.254 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.254 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.254 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.254 DEBUG [Server a4bdb9541dd710b5] delay duration 109.832206ms server | 1.254 DEBUG Setting timeout of 109.832206ms server | 1.256 DEBUG [Server a4bdb9541dd710b5] pn=6 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 35 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 150, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | 1.256 DEBUG [Server a4bdb9541dd710b5] Rx ACK space=ap, ranges=[0..=2] server | 1.256 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 1.256 WARN ECN validation failed, no ECN counts in ACK frame server | 1.256 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5764/16167 ssthresh 18446744073709551615] slow start += 1232 server | 1.256 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5764/16167 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=5764, cwnd=16167, state=SlowStart, new_acked=1232 server | 1.256 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.256 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.256 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.256 DEBUG [Server a4bdb9541dd710b5] delay duration 99.862917ms server | 1.256 DEBUG Setting timeout of 99.862917ms server | 1.256 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.256 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.256 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.256 DEBUG [Server a4bdb9541dd710b5] delay duration 99.802835ms server | 1.256 DEBUG Setting timeout of 99.802835ms server | 1.258 DEBUG [Server a4bdb9541dd710b5] pn=7 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 34 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 9, first_ack_range: 4, ack_ranges: [], ecn_count: None } server | 1.258 DEBUG [Server a4bdb9541dd710b5] Rx ACK space=ap, ranges=[0..=4] server | 1.258 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 1.258 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3300/18631 ssthresh 18446744073709551615] slow start += 2464 server | 1.258 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3300/18631 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=3300, cwnd=18631, state=SlowStart, new_acked=2464 server | 1.258 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.258 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.258 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.258 DEBUG [Server a4bdb9541dd710b5] delay duration 91.353777ms server | 1.258 DEBUG Setting timeout of 91.353777ms server | 1.258 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.258 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.258 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.258 DEBUG [Server a4bdb9541dd710b5] delay duration 91.29096ms server | 1.258 DEBUG Setting timeout of 91.29096ms server | 1.262 DEBUG [Server a4bdb9541dd710b5] pn=8 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 35 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 136, first_ack_range: 6, ack_ranges: [], ecn_count: None } server | 1.262 DEBUG [Server a4bdb9541dd710b5] Rx ACK space=ap, ranges=[0..=6] server | 1.262 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6 server | 1.262 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20559 ssthresh 18446744073709551615] slow start += 1928 server | 1.262 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20559 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=1372, cwnd=20559, state=SlowStart, new_acked=1928 server | 1.262 DEBUG Acked crypto frame space=ap offset=0 length=305 server | 1.262 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.262 DEBUG [Server a4bdb9541dd710b5] pn=9 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 28 server | TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 } server | 1.262 DEBUG packet_sent this=0x564290dd8b40, pn=9, ps=29 server | 1.262 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.262 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG [Server a4bdb9541dd710b5] delay duration 89.496924ms server | 1.262 DEBUG Setting timeout of 89.496924ms server | 1.262 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.262 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.262 DEBUG [Server a4bdb9541dd710b5] delay duration 89.470745ms server | 1.262 DEBUG Setting timeout of 89.470745ms server | 1.263 DEBUG [Server a4bdb9541dd710b5] pn=9 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 33 server | -> RX ConnectionClose { error_code: Application(256), frame_type: 0, reason_phrase: "" } server | 1.263 INFO [Server a4bdb9541dd710b5] ConnectionClose received. Error code: Application(256) frame type 0 reason server | 1.263 DEBUG [Server a4bdb9541dd710b5] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(256)), timeout: Instant { tv_sec: 565, tv_nsec: 957988484 } } server | 1.263 DEBUG [pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470] Path validated Instant { tv_sec: 565, tv_nsec: 689162738 } server | 1.263 DEBUG [Server a4bdb9541dd710b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.263 DEBUG Building Short dcid Some(CID [8]: f16a9171f671c53b) server | 1.263 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 565, tv_nsec: 689162738 } server | 1.263 DEBUG [Server a4bdb9541dd710b5] pn=10 type=Short pri-path:f16a9171f671c53b [::]:443->[::ffff:193.167.0.100]:60470 IpTos(Cs0, NotEct) len 35 server | TX -> Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 1.263 DEBUG Setting timeout of 268.724227ms server | 1.263 DEBUG Setting timeout of 268.711473ms server | 1.533 DEBUG [Server a4bdb9541dd710b5] State change from Draining { error: Transport(PeerApplicationError(256)), timeout: Instant { tv_sec: 565, tv_nsec: 957988484 } } -> Closed(Transport(PeerApplicationError(256))) server | 1.533 INFO Closing timer expired server | 1.781 DEBUG [Server] Handle initial server | 1.781 INFO AddressValidation: no token; accepting server | 1.781 INFO [Server] Accept connection CID [8]: ec81eb514b2019e5 server | 1.781 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.783 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.783 INFO [Server ...] Received valid Initial packet with scid CID [8]: f9dfe6e9c7df56c3 dcid CID [8]: ec81eb514b2019e5 server | 1.783 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=ec81eb514b2019e5 server | 1.783 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.783 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.783 DEBUG [Server ec81eb514b2019e5] State change from Init -> WaitInitial server | 1.783 DEBUG [Server ec81eb514b2019e5] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 855 server | -> RX Crypto { offset: 0, len: 807 } server | 1.783 DEBUG Read Ok(807) bytes server | 1.783 INFO 0-RTT: transport parameters OK, passing to application checker server | 1.783 WARN AllowZeroRtt accepting 0-RTT server | 1.784 DEBUG Read secret available for ZeroRtt: SymKey [32]: e4679ff9751f140247f6b8d7b475ad4b8ddce5adddf3bdc05edc62efe4bfc89f server | 1.784 DEBUG Read secret available for Handshake: SymKey [32]: ad6d1a83e99a212f089d576796f81857e0eb573692723d78f43cfc8945d5b6f3 server | 1.784 DEBUG Write secret available for Handshake: SymKey [32]: 5d4401c8229c6523bb2639ff5cc9cf34b6868d0434eb21b17982d9ff68fc002b server | 1.784 DEBUG Writing transport parameters, msg=8 server | 1.784 DEBUG Read secret available for ApplicationData: SymKey [32]: c0de6b571120e1b12860fc5b157326bd3e09fdd9392d1ef87485783458d8fa05 server | 1.784 DEBUG Write secret available for ApplicationData: SymKey [32]: 609fd5e54104b54b75bb8b440bafc0afefc7a501431cc8cc5002aedab9e7b072 server | 1.784 DEBUG [Agent 0x564290dd6a70] state -> InProgress server | 1.784 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.784 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.784 DEBUG [Crypto] Handshake keys installed server | 1.784 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.784 DEBUG [Crypto] Application write key installed server | 1.784 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 566, tv_nsec: 207786092 } server | 1.784 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:51749] Make permanent server | 1.784 DEBUG [unv-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749] set as primary path server | 1.784 DEBUG [Server ec81eb514b2019e5] State change from WaitInitial -> Handshaking server | 1.784 DEBUG Making Read ZeroRtt CryptoDxState, v=Version1 cipher=4865 server | 1.784 DEBUG [Server ec81eb514b2019e5] Accepted 0-RTT server | 1.784 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1 server | 1.784 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.784 DEBUG Building Initial dcid Some(CID [8]: f9dfe6e9c7df56c3) scid Some(CID [10]: dcd6bb2a8c7904fa1f93) server | 1.784 DEBUG CRYPTO for in offset=0, len=96 server | 1.784 DEBUG [Server ec81eb514b2019e5] pn=0 type=Initial pri-unv-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 150 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 96 } server | 1.784 DEBUG Building Handshake dcid Some(CID [8]: f9dfe6e9c7df56c3) scid Some(CID [10]: dcd6bb2a8c7904fa1f93) server | 1.784 DEBUG CRYPTO for hs offset=0, len=178 server | 1.784 DEBUG [Server ec81eb514b2019e5] pn=0 type=Handshake pri-unv-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 376 server | TX -> Crypto { offset: 0, len: 178 } server | 1.784 DEBUG packet_sent this=0x564290dd8b40, pn=0, ps=226 server | 1.784 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.784 DEBUG [Server ec81eb514b2019e5] pad Initial from 376 to PLPMTU 1232 server | 1.784 DEBUG packet_sent this=0x564290dd8b40, pn=0, ps=1006 server | 1.784 DEBUG ECN probing: sent 1 probes server | 1.784 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.784 DEBUG Building Initial dcid Some(CID [8]: f9dfe6e9c7df56c3) scid Some(CID [10]: dcd6bb2a8c7904fa1f93) server | 1.784 DEBUG Building Handshake dcid Some(CID [8]: f9dfe6e9c7df56c3) scid Some(CID [10]: dcd6bb2a8c7904fa1f93) server | 1.784 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.784 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.784 DEBUG [Server ec81eb514b2019e5] delay duration 297.222889ms server | 1.784 DEBUG Setting timeout of 297.222889ms server | 1.784 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.784 DEBUG Building Initial dcid Some(CID [8]: f9dfe6e9c7df56c3) scid Some(CID [10]: dcd6bb2a8c7904fa1f93) server | 1.784 DEBUG Building Handshake dcid Some(CID [8]: f9dfe6e9c7df56c3) scid Some(CID [10]: dcd6bb2a8c7904fa1f93) server | 1.784 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.784 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.784 DEBUG [Server ec81eb514b2019e5] delay duration 297.185429ms server | 1.784 DEBUG Setting timeout of 297.185429ms server | 1.819 DEBUG [Server ec81eb514b2019e5] pn=1 type=Initial pri-unv-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 52 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 197, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.819 DEBUG [Server ec81eb514b2019e5] Rx ACK space=in, ranges=[0..=0] server | 1.819 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 1.819 DEBUG on_packets_acked this=0x564290dd8b40, limited=1, bytes_in_flight=226, cwnd=12320, state=SlowStart, new_acked=1006 server | 1.819 DEBUG Acked crypto frame space=in offset=0 length=96 server | 1.819 DEBUG [pri-unv-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749] Path validated Instant { tv_sec: 566, tv_nsec: 244952291 } server | 1.819 DEBUG [Server ec81eb514b2019e5] Drop packet number space in server | 1.819 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.819 DEBUG [Server ec81eb514b2019e5] pn=2 type=Handshake pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 91 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 197, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Crypto { offset: 0, len: 36 } server | 1.819 DEBUG [Server ec81eb514b2019e5] Rx ACK space=hs, ranges=[0..=0] server | 1.819 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.819 DEBUG on_packets_acked this=0x564290dd8b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=226 server | 1.819 DEBUG Acked crypto frame space=hs offset=0 length=178 server | 1.819 DEBUG Read Ok(36) bytes server | 1.819 DEBUG [Agent 0x564290dd6a70] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: true, early_data: true, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 1.819 DEBUG [Server ec81eb514b2019e5] TLS connection complete server | 1.819 DEBUG [pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749] Path validated Instant { tv_sec: 566, tv_nsec: 244952291 } server | 1.819 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.819 DEBUG [Crypto] application read keys installed server | 1.819 DEBUG [Server ec81eb514b2019e5] State change from Handshaking -> Connected server | 1.819 DEBUG [Server ec81eb514b2019e5] State change from Connected -> Confirmed server | 1.819 DEBUG PMTUD started with probe size 1380 server | 1.819 INFO [Server ec81eb514b2019e5] Connection established server | 1.819 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 566, tv_nsec: 244952291 } server | 1.819 DEBUG [Server ec81eb514b2019e5] pn=3 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 1057 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [61, 223, 213, 115, 13, 146, 104, 136], stateless_reset_token: [123, 50, 143, 126, 240, 78, 112, 98, 202, 116, 65, 160, 199, 166, 43, 94] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [26, 123, 35, 250, 186, 50, 19, 155], stateless_reset_token: [134, 98, 77, 234, 198, 181, 151, 120, 89, 193, 147, 70, 126, 2, 227, 63] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [180, 231, 178, 234, 134, 98, 145, 217], stateless_reset_token: [110, 184, 46, 80, 171, 235, 138, 85, 115, 47, 252, 35, 131, 233, 230, 155] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [174, 129, 62, 179, 9, 35, 190, 77], stateless_reset_token: [104, 202, 220, 97, 92, 200, 117, 230, 20, 213, 49, 181, 118, 51, 191, 255] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [132, 130, 237, 150, 148, 126, 29, 43], stateless_reset_token: [111, 67, 60, 17, 224, 142, 121, 221, 169, 112, 106, 128, 179, 51, 11, 154] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [253, 167, 60, 9, 165, 21, 225, 46], stateless_reset_token: [213, 195, 0, 79, 188, 203, 201, 24, 107, 119, 88, 180, 116, 240, 113, 205] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [204, 223, 135, 67, 144, 227, 122, 141], stateless_reset_token: [224, 48, 43, 143, 54, 228, 89, 225, 190, 85, 87, 101, 28, 41, 138, 55] } server | -> RX Padding { len: 832 } server | 1.819 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 566, tv_nsec: 244952291 } server | 1.819 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.819 DEBUG Building Handshake dcid Some(CID [8]: f9dfe6e9c7df56c3) scid Some(CID [10]: dcd6bb2a8c7904fa1f93) server | 1.819 DEBUG [Server ec81eb514b2019e5] pn=1 type=Handshake pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 49 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.819 DEBUG [Server ec81eb514b2019e5] Drop packet number space hs server | 1.819 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.819 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.819 DEBUG [Server ec81eb514b2019e5] pn=0 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1332 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [101, 67, 87, 81, 22, 228, 154, 144, 146, 6], stateless_reset_token: [253, 194, 83, 42, 33, 242, 103, 70, 90, 158, 173, 39, 19, 204, 252, 237] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [119, 107, 60, 26, 232, 192, 39, 34, 47, 93], stateless_reset_token: [53, 142, 189, 11, 51, 187, 216, 202, 132, 15, 242, 146, 235, 232, 194, 14] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [175, 246, 212, 150, 133, 145, 45, 15, 254, 179], stateless_reset_token: [32, 91, 69, 226, 238, 217, 90, 26, 208, 131, 161, 41, 19, 10, 40, 162] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [253, 50, 208, 50, 11, 115, 147, 173, 207, 119], stateless_reset_token: [21, 117, 194, 143, 217, 59, 85, 253, 136, 106, 119, 34, 70, 250, 234, 144] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [142, 162, 154, 237, 40, 254, 141, 217, 134, 199], stateless_reset_token: [124, 132, 114, 28, 19, 101, 70, 164, 187, 161, 106, 13, 125, 81, 83, 210] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [11, 33, 251, 129, 63, 74, 73, 26, 86, 24], stateless_reset_token: [122, 205, 98, 10, 74, 117, 33, 107, 114, 251, 44, 183, 254, 59, 16, 121] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [1, 106, 187, 28, 209, 156, 147, 198, 21, 165], stateless_reset_token: [229, 144, 104, 126, 142, 68, 202, 8, 186, 252, 55, 178, 136, 134, 135, 171] } server | TX -> Padding { len: 1041 } server | 1.819 DEBUG packet_sent this=0x564290dd8b40, pn=0, ps=1283 server | 1.819 DEBUG ECN probing: sent 2 probes server | 1.819 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.819 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.819 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.819 DEBUG [Server ec81eb514b2019e5] pn=1 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 1.819 DEBUG packet_sent this=0x564290dd8b40, pn=1, ps=1332 server | 1.819 DEBUG ECN probing: sent 3 probes server | 1.819 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.819 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.819 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.819 DEBUG [Server ec81eb514b2019e5] delay duration 1.38567ms server | 1.819 DEBUG Setting timeout of 1.38567ms server | 1.819 DEBUG [Server ec81eb514b2019e5] pn=4 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 50 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 1.819 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 566, tv_nsec: 265680317 } server | 1.819 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.819 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.819 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.819 DEBUG [Server ec81eb514b2019e5] delay duration 1.359341ms server | 1.819 DEBUG Setting timeout of 1.359341ms server | 1.819 DEBUG [Server ec81eb514b2019e5] send session ticket 40630008ec81eb514b2019e50104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0adcd6bb2a8c7904fa1f93110c00000001daea7aea000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.820 DEBUG Path = 'naiwwkzkdc' server | 1.820 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.820 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.820 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.820 DEBUG [Server ec81eb514b2019e5] delay duration 1.151925ms server | 1.820 DEBUG Setting timeout of 1.151925ms server | 1.822 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.822 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.822 DEBUG [Server ec81eb514b2019e5] pn=2 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 1.822 DEBUG packet_sent this=0x564290dd8b40, pn=2, ps=1232 server | 1.822 DEBUG ECN probing: sent 4 probes server | 1.822 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.822 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.822 DEBUG [Server ec81eb514b2019e5] pn=3 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 1.822 DEBUG packet_sent this=0x564290dd8b40, pn=3, ps=1232 server | 1.822 DEBUG ECN probing: sent 5 probes server | 1.822 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.822 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.822 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.822 DEBUG [Server ec81eb514b2019e5] delay duration 1.785032ms server | 1.822 DEBUG Setting timeout of 1.785032ms server | 1.822 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.822 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.822 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.822 DEBUG [Server ec81eb514b2019e5] delay duration 1.761939ms server | 1.822 DEBUG Setting timeout of 1.761939ms server | 1.825 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.825 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.825 DEBUG [Server ec81eb514b2019e5] pn=4 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false } server | 1.825 DEBUG packet_sent this=0x564290dd8b40, pn=4, ps=1232 server | 1.825 DEBUG ECN probing: sent 6 probes server | 1.825 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.825 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.825 DEBUG [Server ec81eb514b2019e5] pn=5 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false } server | 1.825 DEBUG packet_sent this=0x564290dd8b40, pn=5, ps=1232 server | 1.825 DEBUG ECN probing: sent 7 probes server | 1.825 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.825 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.825 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.825 DEBUG [Server ec81eb514b2019e5] delay duration 1.754636ms server | 1.825 DEBUG Setting timeout of 1.754636ms server | 1.825 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.825 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.825 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.825 DEBUG [Server ec81eb514b2019e5] delay duration 1.738005ms server | 1.825 DEBUG Setting timeout of 1.738005ms server | 1.828 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.828 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.828 DEBUG [Server ec81eb514b2019e5] pn=6 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 4810, len: >>1202, fin: false } server | 1.828 DEBUG packet_sent this=0x564290dd8b40, pn=6, ps=1232 server | 1.828 DEBUG ECN probing: sent 8 probes server | 1.828 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.828 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.828 DEBUG [Server ec81eb514b2019e5] pn=7 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 6012, len: >>1202, fin: false } server | 1.828 DEBUG packet_sent this=0x564290dd8b40, pn=7, ps=1232 server | 1.828 DEBUG ECN probing: sent 9 probes server | 1.828 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.828 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.828 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.828 DEBUG [Server ec81eb514b2019e5] delay duration 1.775034ms server | 1.828 DEBUG Setting timeout of 1.775034ms server | 1.828 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.828 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.828 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.828 DEBUG [Server ec81eb514b2019e5] delay duration 1.753975ms server | 1.828 DEBUG Setting timeout of 1.753975ms server | 1.831 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.831 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.831 DEBUG [Server ec81eb514b2019e5] pn=8 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 7214, len: >>1202, fin: false } server | 1.831 DEBUG packet_sent this=0x564290dd8b40, pn=8, ps=1232 server | 1.831 DEBUG ECN probing: sent 10 probes server | 1.831 DEBUG ECN probing concluded with 10 probes sent server | 1.831 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1081, pto: None, probe: EnumSet(), paced: false } server | 1.831 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.831 DEBUG [Server ec81eb514b2019e5] pn=9 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 1081 server | TX -> Stream { stream_id: 0, offset: 8416, len: >>1051, fin: false } server | 1.831 DEBUG packet_sent this=0x564290dd8b40, pn=9, ps=1081 server | 1.831 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.831 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.831 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.831 DEBUG [Server ec81eb514b2019e5] delay duration 8.645327ms server | 1.831 DEBUG Setting timeout of 8.645327ms server | 1.831 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.831 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.831 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.831 DEBUG [Server ec81eb514b2019e5] delay duration 8.626081ms server | 1.831 DEBUG Setting timeout of 8.626081ms server | 1.841 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.841 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.841 DEBUG [Server ec81eb514b2019e5] pn=10 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 32 server | TX -> Ack { largest_acknowledged: 4, ack_delay: 2683, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.841 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.841 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.841 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.841 DEBUG [Server ec81eb514b2019e5] delay duration 70.643785ms server | 1.841 DEBUG Setting timeout of 70.643785ms server | 1.841 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.841 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.841 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.841 DEBUG [Server ec81eb514b2019e5] delay duration 70.61942ms server | 1.841 DEBUG Setting timeout of 70.61942ms server | 1.852 DEBUG [Server ec81eb514b2019e5] pn=5 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 34 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 12, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.852 DEBUG [Server ec81eb514b2019e5] Rx ACK space=ap, ranges=[0..=1] server | 1.852 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 1.852 WARN ECN validation failed, no ECN counts in ACK frame server | 1.852 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9705/14935 ssthresh 18446744073709551615] slow start += 2615 server | 1.852 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9705/14935 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=9705, cwnd=14935, state=SlowStart, new_acked=2615 server | 1.852 DEBUG PMTUD probe of size 1380 succeeded server | 1.852 DEBUG PMTUD started with probe size 1420 server | 1.852 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.852 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.852 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.852 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.852 DEBUG CRYPTO for ap offset=0, len=305 server | 1.852 DEBUG [Server ec81eb514b2019e5] pn=11 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 1372 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 9467, len: 773, fin: true } server | TX -> Crypto { offset: 0, len: 305 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 201, 237, 63, 134, 242, 130, 223, 56, 35, 134, 249, 52, 37, 19, 19, 198, 18, 4, 124, 252, 221, 140, 40, 168, 254, 46, 132, 196, 40, 41, 149, 237, 238, 24, 138, 250] } server | TX -> Padding { len: 212 } server | 1.852 DEBUG packet_sent this=0x564290dd8b40, pn=11, ps=1372 server | 1.852 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.852 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.852 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.852 DEBUG [Server ec81eb514b2019e5] delay duration 59.125219ms server | 1.852 DEBUG Setting timeout of 59.125219ms server | 1.852 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.852 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.852 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.852 DEBUG [Server ec81eb514b2019e5] delay duration 59.100844ms server | 1.852 DEBUG Setting timeout of 59.100844ms server | 1.854 DEBUG [Server ec81eb514b2019e5] pn=6 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 34 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 6, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 1.854 DEBUG [Server ec81eb514b2019e5] Rx ACK space=ap, ranges=[0..=3] server | 1.854 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3 server | 1.854 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8613/17399 ssthresh 18446744073709551615] slow start += 2464 server | 1.854 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8613/17399 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=8613, cwnd=17399, state=SlowStart, new_acked=2464 server | 1.854 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.854 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.854 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.854 DEBUG [Server ec81eb514b2019e5] delay duration 57.280103ms server | 1.854 DEBUG Setting timeout of 57.280103ms server | 1.854 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.854 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.854 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.854 DEBUG [Server ec81eb514b2019e5] delay duration 57.231834ms server | 1.854 DEBUG Setting timeout of 57.231834ms server | 1.858 DEBUG [Server ec81eb514b2019e5] pn=7 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 34 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 8, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 1.858 DEBUG [Server ec81eb514b2019e5] Rx ACK space=ap, ranges=[0..=5] server | 1.858 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5 server | 1.858 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6149/19863 ssthresh 18446744073709551615] slow start += 2464 server | 1.858 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6149/19863 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=6149, cwnd=19863, state=SlowStart, new_acked=2464 server | 1.858 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.858 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.858 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.858 DEBUG [Server ec81eb514b2019e5] delay duration 54.001008ms server | 1.858 DEBUG Setting timeout of 54.001008ms server | 1.858 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.858 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.858 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.858 DEBUG [Server ec81eb514b2019e5] delay duration 53.954452ms server | 1.858 DEBUG Setting timeout of 53.954452ms server | 1.861 DEBUG [Server ec81eb514b2019e5] pn=8 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 34 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 7, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | 1.861 DEBUG [Server ec81eb514b2019e5] Rx ACK space=ap, ranges=[0..=7] server | 1.861 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7 server | 1.861 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3685/22327 ssthresh 18446744073709551615] slow start += 2464 server | 1.861 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3685/22327 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=3685, cwnd=22327, state=SlowStart, new_acked=2464 server | 1.861 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.861 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.861 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.861 DEBUG [Server ec81eb514b2019e5] delay duration 50.733174ms server | 1.861 DEBUG Setting timeout of 50.733174ms server | 1.861 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.861 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.861 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.861 DEBUG [Server ec81eb514b2019e5] delay duration 50.688501ms server | 1.861 DEBUG Setting timeout of 50.688501ms server | 1.863 DEBUG [Server ec81eb514b2019e5] pn=9 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 34 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 16, first_ack_range: 9, ack_ranges: [], ecn_count: None } server | 1.863 DEBUG [Server ec81eb514b2019e5] Rx ACK space=ap, ranges=[0..=9] server | 1.863 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=9 server | 1.863 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] slow start += 2313 server | 1.863 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] on_packets_acked this=0x564290dd8b40, limited=0, bytes_in_flight=1372, cwnd=24640, state=SlowStart, new_acked=2313 server | 1.863 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.863 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.863 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.863 DEBUG [Server ec81eb514b2019e5] delay duration 48.503813ms server | 1.863 DEBUG Setting timeout of 48.503813ms server | 1.863 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.863 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.863 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.863 DEBUG [Server ec81eb514b2019e5] delay duration 48.458489ms server | 1.863 DEBUG Setting timeout of 48.458489ms server | 1.885 DEBUG [Server ec81eb514b2019e5] pn=10 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 33 server | -> RX ConnectionClose { error_code: Application(256), frame_type: 0, reason_phrase: "" } server | 1.885 INFO [Server ec81eb514b2019e5] ConnectionClose received. Error code: Application(256) frame type 0 reason server | 1.885 DEBUG [Server ec81eb514b2019e5] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(256)), timeout: Instant { tv_sec: 566, tv_nsec: 561914019 } } server | 1.885 DEBUG [pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749] Path validated Instant { tv_sec: 566, tv_nsec: 311355588 } server | 1.885 DEBUG [Server ec81eb514b2019e5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.885 DEBUG Building Short dcid Some(CID [8]: f9dfe6e9c7df56c3) server | 1.885 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 566, tv_nsec: 311355588 } server | 1.885 DEBUG [Server ec81eb514b2019e5] pn=12 type=Short pri-path:f9dfe6e9c7df56c3 [::]:443->[::ffff:193.167.0.100]:51749 IpTos(Cs0, NotEct) len 35 server | TX -> Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 1.885 DEBUG Setting timeout of 250.459847ms server | 1.885 DEBUG Setting timeout of 250.441323ms server | 2.137 DEBUG [Server ec81eb514b2019e5] State change from Draining { error: Transport(PeerApplicationError(256)), timeout: Instant { tv_sec: 566, tv_nsec: 561914019 } } -> Closed(Transport(PeerApplicationError(256))) server | 2.137 INFO Closing timer expired client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 12:25:34,983 Using the client's key log file. 2025-04-30 12:25:34,991 2025-04-30 12:25:34,992 Using the client's key log file. 2025-04-30 12:25:34,992 Using the client's key log file. 2025-04-30 12:25:34,999 2025-04-30 12:25:35,000 Using the client's key log file. 2025-04-30 12:25:35,000 Using the client's key log file. 2025-04-30 12:25:35,854 Check of downloaded files succeeded.