2025-12-13 19:54:50,364 Generated random file: massive-black-farmer of size: 10240 2025-12-13 19:54:50,364 Requests: https://server4:443/massive-black-farmer 2025-12-13 19:54:50,423 2025-12-13 19:54:50,423 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_0vo_etg0/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_edl26u8p/ DOWNLOADS=/tmp/download_uj8iuv8z/ SERVER_LOGS=/tmp/logs_server_ypxbd9p_ CLIENT_LOGS=/tmp/logs_client_qzr11q7v SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/alibaba/xquic/xquic-interop:latest REQUESTS="https://server4:443/massive-black-farmer" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-12-13 19:55:02,704 Container client Recreate Container server Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 client | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + '[' -n retry ']' client | + '[' -n /logs/qlog/ ']' client | + case "$ROLE" in client | + /wait-for-it.sh sim:57832 -s -t 30 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | Retry test: adding -r flag server | ./demo_server -l d -L /logs/server.log -p 443 -D /www -k /logs/keys.log -i -M -r server | option log level :d server | option log directory :/logs/server.log server | option port :443 server | option read dir :/www server | option key output file: /logs/keys.log server | set interop mode server | option multipath enabled server | option validate addr with retry packet server | /logs/keys.log 4 server | create ipv4 socket fd: 9 server | create ipv6 socket fd: 10 sim | server:443 is available after 501.78961ms sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | Capturing on 'eth1' sim | Capturing on 'eth0' sim | File: /logs/trace_node_right.pcap sim | File: /logs/trace_node_left.pcap client | wait-for-it.sh: sim:57832 is available after 1 seconds client | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads) client | + '[' https://server4:443/massive-black-farmer ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test retry --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/massive-black-farmer client | ' client | ++ tee -i -a /logs/client.log client | 0.000 DEBUG Logging initialized client | 0.001 DEBUG Default socket send buffer size is Ok(212992) client | 0.001 DEBUG Default socket receive buffer size is 1048576, not changing client | 0.001 INFO hq-interop Client connecting: 0.0.0.0:53029 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=732bc4e1eace5dc9368b client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=12 client | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.001 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 0.001 DEBUG [unv-path 0.0.0.0:53029->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:732bc4e1eace5dc9368b 0.0.0.0:53029->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:732bc4e1eace5dc9368b 0.0.0.0:53029->193.167.100.100:443] Path validated Instant { tv_sec: 240, tv_nsec: 268684678 } client | 0.001 DEBUG [Client 732bc4e1eace5dc9368b] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x5604a9ea5c60] state -> InProgress client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] State change from Init -> WaitInitial client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [10]: 732bc4e1eace5dc9368b) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1211 client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] pn=12 type=Initial pri-path:732bc4e1eace5dc9368b 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1211 } client | 0.002 DEBUG packet_sent this=0x5604a9eb8d40, pn=12, ps=1252 client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [10]: 732bc4e1eace5dc9368b) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1211, len=327 client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] pn=13 type=Initial pri-path:732bc4e1eace5dc9368b 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 369 client | TX -> Crypto { offset: 1211, len: 327 } client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] pad Initial from 369 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x5604a9eb8d40, pn=13, ps=1252 client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [10]: 732bc4e1eace5dc9368b) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [10]: 732bc4e1eace5dc9368b) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 3.300179ms client | 0.002 DEBUG Setting timeout of 3.300179ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [10]: 732bc4e1eace5dc9368b) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 3.270954ms client | 0.002 DEBUG Setting timeout of 3.270954ms client | 0.006 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.006 DEBUG Building Initial dcid Some(CID [10]: 732bc4e1eace5dc9368b) scid Some(CID [0]: ) client | 0.006 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.006 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 294.749691ms client | 0.006 DEBUG Setting timeout of 294.749691ms client | 0.039 INFO [Client 732bc4e1eace5dc9368b] received Retry client | 0.039 INFO [Client 732bc4e1eace5dc9368b] Valid Retry received, token=cdb220733470c2fd652eb1c6003c5ba23881817290bb83d2b5f4f088aaa0e6edbd84a86ab5 scid=6f7532b6f25907d3b71a65d5 client | 0.039 INFO [pri-path:6f7532b6f25907d3b71a65d5 0.0.0.0:53029->193.167.100.100:443] discarding a packet without an RTT estimate; guessing RTT=37.854161ms client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1211 }) client | 0.039 INFO Lost crypto frame space=in offset=0 length=1211 client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1211, length: 327 }) client | 0.039 INFO Lost crypto frame space=in offset=1211 length=327 client | 0.039 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=6f7532b6f25907d3b71a65d5 client | 0.039 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.039 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.039 INFO [CryptoStates] Continue packet numbers for initial after retry (write is 0..0) client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [12]: 6f7532b6f25907d3b71a65d5) scid Some(CID [0]: ) client | 0.039 DEBUG CRYPTO for in offset=0, len=1172 client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] pn=14 type=Initial pri-path:6f7532b6f25907d3b71a65d5 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1172 } client | 0.039 DEBUG packet_sent this=0x5604a9eb8d40, pn=14, ps=1252 client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [12]: 6f7532b6f25907d3b71a65d5) scid Some(CID [0]: ) client | 0.039 DEBUG CRYPTO for in offset=1172, len=366 client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] pn=15 type=Initial pri-path:6f7532b6f25907d3b71a65d5 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 447 client | TX -> Crypto { offset: 1172, len: 366 } client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] pad Initial from 447 to PLPMTU 1252 client | 0.039 DEBUG packet_sent this=0x5604a9eb8d40, pn=15, ps=1252 client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [12]: 6f7532b6f25907d3b71a65d5) scid Some(CID [0]: ) client | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [12]: 6f7532b6f25907d3b71a65d5) scid Some(CID [0]: ) client | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.432937ms client | 0.039 DEBUG Setting timeout of 113.432937ms client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG Building Initial dcid Some(CID [12]: 6f7532b6f25907d3b71a65d5) scid Some(CID [0]: ) client | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.039 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.40781ms client | 0.039 DEBUG Setting timeout of 113.40781ms client | 0.071 DEBUG [Client 732bc4e1eace5dc9368b] pn=0 type=Initial pri-path:6f7532b6f25907d3b71a65d5 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 85 client | -> RX Ack { largest_acknowledged: 14, ack_delay: 64, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.071 DEBUG [Client 732bc4e1eace5dc9368b] Rx ACK space=in, ranges=[14..=14] client | 0.071 DEBUG [recovery::Loss] ACK for Initial - largest_acked=14 client | 0.071 DEBUG on_packets_acked this=0x5604a9eb8d40, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252 client | 0.071 DEBUG Acked crypto frame space=in offset=0 length=1172 client | 0.071 DEBUG [Client 732bc4e1eace5dc9368b] Changing to use Server CID=aea40e581318c9a500666d6f client | 0.071 DEBUG [Client 732bc4e1eace5dc9368b] State change from WaitInitial -> WaitVersion client | 0.071 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.071 DEBUG Building Initial dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.071 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.071 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 67.508614ms client | 0.071 DEBUG Setting timeout of 67.508614ms client | 0.071 WARN Unhandled event StateChange(WaitVersion) client | 0.071 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.071 DEBUG Building Initial dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.071 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.071 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 67.482185ms client | 0.071 DEBUG Setting timeout of 67.482185ms client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] pn=1 type=Initial pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1200 client | -> RX Crypto { offset: 0, len: 90 } client | -> RX Padding { len: 1027 } client | 0.074 DEBUG Write secret available for Handshake: SymKey [32]: 139be805e6e1e922ce77e917a385510e5bcb44cd54ab0c44ad8e1f14dc0fa346 client | 0.074 DEBUG Read secret available for Handshake: SymKey [32]: 48a33974a58d1b365fc5893c65cab91161da2bafbdab52d6123e9eeec8977e95 client | 0.074 DEBUG [Agent 0x5604a9ea5c60] state -> InProgress client | 0.074 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.074 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.074 DEBUG [Crypto] Handshake keys installed client | 0.074 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 341607185 } client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] State change from WaitVersion -> Handshaking client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Initial dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] pn=16 type=Initial pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 82 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 68, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.074 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] pad Initial from 82 to PLPMTU 1252 client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Initial dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Initial dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 64.79568ms client | 0.074 DEBUG Setting timeout of 64.79568ms client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] pn=0 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 199 client | -> RX Crypto { offset: 0, len: 154 } client | 0.074 DEBUG [Agent 0x5604a9ea5c60] state -> InProgress client | 0.074 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 342297101 } client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Initial dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] pn=0 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 5, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] Drop packet number space in client | 0.074 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 99.588754ms client | 0.074 DEBUG Setting timeout of 99.588754ms client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] pn=1 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 516 client | -> RX Crypto { offset: 154, len: 470 } client | 0.074 DEBUG [Agent 0x5604a9ea5c60] state -> AuthenticationPending client | 0.074 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 342447862 } client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.074 DEBUG [Client 732bc4e1eace5dc9368b] pn=1 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 16, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 99.377761ms client | 0.075 DEBUG Setting timeout of 99.377761ms client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] pn=2 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 125 client | -> RX Crypto { offset: 624, len: 79 } client | 0.075 DEBUG [Agent 0x5604a9ea5c60] state -> AuthenticationPending client | 0.075 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 342648746 } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] pn=2 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 77, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 98.688086ms client | 0.075 DEBUG Setting timeout of 98.688086ms client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] pn=3 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 81 client | -> RX Crypto { offset: 703, len: 36 } client | 0.075 DEBUG [Agent 0x5604a9ea5c60] state -> AuthenticationPending client | 0.075 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 343342388 } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] pn=3 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 43 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 6, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 98.546352ms client | 0.075 DEBUG Setting timeout of 98.546352ms client | 0.075 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] pn=4 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 39 client | TX -> Ping client | 0.075 DEBUG packet_sent this=0x5604a9eb8d40, pn=4, ps=41 client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 99.605925ms client | 0.075 DEBUG Setting timeout of 99.605925ms client | 0.075 DEBUG StateChange(Handshaking) client | 0.075 WARN Cannot create stream ConnectionState client | 0.075 DEBUG stream BiDi creatable client | 0.075 WARN Cannot create stream ConnectionState client | 0.075 DEBUG stream UniDi creatable client | 0.075 DEBUG [Client 732bc4e1eace5dc9368b] Authenticated Ok client | 0.076 DEBUG Write secret available for ApplicationData: SymKey [32]: f0c19b2dca8f94532994c27154244430756780c6df65fe6ad1871cd9cbb3e927 client | 0.076 DEBUG Read secret available for ApplicationData: SymKey [32]: f864108ded256ac61b2a9f18cf5146d377e6fa2a609f5c231d029ef8c44e6978 client | 0.076 DEBUG [Agent 0x5604a9ea5c60] SSL_AuthCertificateComplete: Ok(()) client | 0.076 DEBUG [Agent 0x5604a9ea5c60] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.076 DEBUG [Client 732bc4e1eace5dc9368b] TLS connection complete client | 0.076 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.076 DEBUG [Crypto] Application write key installed client | 0.076 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.076 DEBUG [Crypto] application read keys installed client | 0.076 DEBUG [Client 732bc4e1eace5dc9368b] State change from Handshaking -> Connected client | 0.076 INFO [Client 732bc4e1eace5dc9368b] Connection established client | 0.076 DEBUG stream BiDi creatable client | 0.076 INFO Created stream 0 for https://server4/massive-black-farmer client | 0.076 INFO Saving https://server4/massive-black-farmer to "/downloads/massive-black-farmer" client | 0.076 DEBUG stream UniDi creatable client | 0.076 DEBUG StateChange(Connected) client | 0.076 DEBUG stream 0 writable client | 0.076 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.076 DEBUG CRYPTO for hs offset=0, len=36 client | 0.076 DEBUG [Client 732bc4e1eace5dc9368b] pn=5 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 77 client | TX -> Crypto { offset: 0, len: 36 } client | 0.076 DEBUG packet_sent this=0x5604a9eb8d40, pn=5, ps=77 client | 0.076 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.076 DEBUG [Client 732bc4e1eace5dc9368b] pn=0 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 137 client | TX -> Stream { stream_id: 0, offset: 0, len: 27, fin: true } client | 0.076 DEBUG packet_sent this=0x5604a9eb8d40, pn=0, ps=60 client | 0.076 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.076 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 99.549832ms client | 0.076 DEBUG Setting timeout of 99.549832ms server | xqc_hq_parse_req|259||hq recv CR LF| client | 0.107 DEBUG [Client 732bc4e1eace5dc9368b] pn=4 type=Handshake pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 46 client | -> RX Ack { largest_acknowledged: 4, ack_delay: 11, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | 0.107 DEBUG [Client 732bc4e1eace5dc9368b] Rx ACK space=hs, ranges=[0..=4] client | 0.107 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=4 client | 0.107 DEBUG on_packets_acked this=0x5604a9eb8d40, limited=1, bytes_in_flight=137, cwnd=12520, state=SlowStart, new_acked=41 client | 0.107 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.107 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.107 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 58.22736ms client | 0.107 DEBUG Setting timeout of 58.22736ms client | 0.107 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG Building Handshake dcid Some(CID [12]: aea40e581318c9a500666d6f) scid Some(CID [0]: ) client | 0.107 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.107 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.107 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 58.196963ms client | 0.107 DEBUG Setting timeout of 58.196963ms client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] pn=0 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 22 client | -> RX HandshakeDone client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] State change from Connected -> Confirmed client | 0.108 DEBUG PMTUD started, PLPMTU is now 1280 client | 0.108 DEBUG PMTUD started with probe size 1380 client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] Drop packet number space hs client | 0.108 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] No preferred address to migrate to client | 0.108 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 395736529 } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] pn=1 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ping client | TX -> Padding { len: 1321 } client | 0.108 DEBUG packet_sent this=0x5604a9eb8d40, pn=1, ps=1352 client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG ECN probing: sent 1 probes client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.858065ms client | 0.108 DEBUG Setting timeout of 19.858065ms client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] pn=1 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 60 client | -> RX NewToken { token: [42, 204, 4, 166, 196, 247, 34, 188, 202, 204, 55, 118, 0, 145, 218, 64, 67, 14, 206, 19, 239, 128, 173, 147, 75, 225, 216, 29, 57, 8, 118, 219, 156, 216, 32, 72, 71] } client | 0.108 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 375902839 } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] pn=2 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 2, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.108 DEBUG ECN probing: sent 2 probes client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.921212ms client | 0.108 DEBUG Setting timeout of 113.921212ms client | 0.108 WARN Unhandled event StateChange(Confirmed) client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.880737ms client | 0.108 DEBUG Setting timeout of 113.880737ms client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] pn=2 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 282 client | -> RX Crypto { offset: 0, len: 257 } client | 0.108 DEBUG [0x5604a9ea5c60] Got resumption token [920]: 02000645dac050ee..9b84eb1d2952076b client | 0.108 DEBUG [Agent 0x5604a9ea5c60] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.108 DEBUG setting max_stream_data to 16777216 client | 0.108 DEBUG resumption token [1068]: 0000000124406700..9b84eb1d2952076b client | 0.108 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 396051315 } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.887931ms client | 0.108 DEBUG Setting timeout of 19.887931ms client | 0.108 DEBUG stream BiDi creatable client | 0.108 DEBUG stream UniDi creatable client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.86606ms client | 0.108 DEBUG Setting timeout of 19.86606ms client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] pn=3 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 283 client | -> RX Crypto { offset: 257, len: 257 } client | 0.108 DEBUG [0x5604a9ea5c60] Got resumption token [920]: 02000645dac050ef..87844b93e8278697 client | 0.108 DEBUG [Agent 0x5604a9ea5c60] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.108 DEBUG setting max_stream_data to 16777216 client | 0.108 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 376365140 } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] pn=3 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 20, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | 0.108 DEBUG ECN probing: sent 3 probes client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.108 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.327068ms client | 0.109 DEBUG Setting timeout of 113.327068ms client | 0.109 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 2 client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.299316ms client | 0.109 DEBUG Setting timeout of 113.299316ms client | 0.109 DEBUG stream BiDi creatable client | 0.109 DEBUG stream UniDi creatable client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.279569ms client | 0.109 DEBUG Setting timeout of 113.279569ms client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] pn=4 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 53 client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [12, 26, 153, 163, 19, 109, 134, 234, 63, 157, 202, 173], stateless_reset_token: Token([90, 168, 7, 198, 101, 209, 180, 247, 180, 77, 121, 252, 132, 123, 215, 125]) } client | 0.109 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 396739889 } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.970015ms client | 0.109 DEBUG Setting timeout of 19.970015ms client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] pn=5 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 53 client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [12, 225, 63, 51, 82, 94, 98, 67, 210, 139, 117, 85], stateless_reset_token: Token([123, 139, 252, 160, 124, 143, 12, 117, 130, 62, 92, 18, 92, 197, 176, 146]) } client | 0.109 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 376789922 } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] pn=4 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 2, first_ack_range: 5, ack_ranges: [], ecn_count: None } client | 0.109 DEBUG ECN probing: sent 4 probes client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.036855ms client | 0.109 DEBUG Setting timeout of 113.036855ms client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 113.01268ms client | 0.109 DEBUG Setting timeout of 113.01268ms client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] pn=6 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 0, len: 1191, fin: false } client | 0.109 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 397503882 } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.963452ms client | 0.109 DEBUG Setting timeout of 19.963452ms client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.109 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.941521ms client | 0.109 DEBUG Setting timeout of 19.941521ms client | 0.110 DEBUG [Client 732bc4e1eace5dc9368b] pn=7 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 1191, len: 1189, fin: false } client | 0.110 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 378500128 } client | 0.110 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.110 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.110 DEBUG [Client 732bc4e1eace5dc9368b] pn=5 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 3, first_ack_range: 7, ack_ranges: [], ecn_count: None } client | 0.110 DEBUG ECN probing: sent 5 probes client | 0.110 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.110 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 111.322603ms client | 0.111 DEBUG Setting timeout of 111.322603ms client | 0.111 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 111.297647ms client | 0.111 DEBUG Setting timeout of 111.297647ms client | 0.111 DEBUG [Client 732bc4e1eace5dc9368b] pn=8 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 2380, len: 1189, fin: false } client | 0.111 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 399526408 } client | 0.111 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.976206ms client | 0.111 DEBUG Setting timeout of 19.976206ms client | 0.111 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.111 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.961719ms client | 0.111 DEBUG Setting timeout of 19.961719ms client | 0.112 DEBUG [Client 732bc4e1eace5dc9368b] pn=9 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 3569, len: 1189, fin: false } client | 0.112 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 380521181 } client | 0.112 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.112 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.112 DEBUG [Client 732bc4e1eace5dc9368b] pn=6 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 2, first_ack_range: 9, ack_ranges: [], ecn_count: None } client | 0.112 DEBUG ECN probing: sent 6 probes client | 0.112 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.113 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 109.320724ms client | 0.113 DEBUG Setting timeout of 109.320724ms client | 0.113 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.113 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 109.294715ms client | 0.113 DEBUG Setting timeout of 109.294715ms client | 0.113 DEBUG [Client 732bc4e1eace5dc9368b] pn=10 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 4758, len: 1189, fin: false } client | 0.113 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 401514584 } client | 0.113 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.113 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.971517ms client | 0.113 DEBUG Setting timeout of 19.971517ms client | 0.113 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.113 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.113 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.948855ms client | 0.113 DEBUG Setting timeout of 19.948855ms client | 0.114 DEBUG [Client 732bc4e1eace5dc9368b] pn=11 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 5947, len: 1189, fin: false } client | 0.114 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 382508335 } client | 0.114 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.114 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.114 DEBUG [Client 732bc4e1eace5dc9368b] pn=7 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 11, ack_delay: 3, first_ack_range: 11, ack_ranges: [], ecn_count: None } client | 0.114 DEBUG ECN probing: sent 7 probes client | 0.114 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 107.322419ms client | 0.115 DEBUG Setting timeout of 107.322419ms client | 0.115 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 107.296911ms client | 0.115 DEBUG Setting timeout of 107.296911ms client | 0.115 DEBUG [Client 732bc4e1eace5dc9368b] pn=12 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 7136, len: 1189, fin: false } client | 0.115 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 403508979 } client | 0.115 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.976255ms client | 0.115 DEBUG Setting timeout of 19.976255ms client | 0.115 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.115 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.953713ms client | 0.115 DEBUG Setting timeout of 19.953713ms client | 0.116 DEBUG [Client 732bc4e1eace5dc9368b] pn=13 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 1216 client | -> RX Stream { stream_id: 0, offset: 8325, len: 1189, fin: false } client | 0.116 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 384500918 } client | 0.116 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.116 DEBUG [Client 732bc4e1eace5dc9368b] pn=8 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 35 client | TX -> Ack { largest_acknowledged: 13, ack_delay: 2, first_ack_range: 13, ack_ranges: [], ecn_count: None } client | 0.116 DEBUG ECN probing: sent 8 probes client | 0.116 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.116 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 105.33718ms client | 0.116 DEBUG Setting timeout of 105.33718ms client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.117 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 105.309809ms client | 0.117 DEBUG Setting timeout of 105.309809ms client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] pn=14 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 753 client | -> RX Stream { stream_id: 0, offset: 9514, len: 726, fin: true } client | 0.117 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 240, tv_nsec: 405064397 } client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.117 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.976977ms client | 0.117 DEBUG Setting timeout of 19.976977ms client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.117 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] delay duration 19.927445ms client | 0.117 DEBUG Setting timeout of 19.927445ms client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 240, tv_nsec: 727510327 } } client | 0.117 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 240, tv_nsec: 727510327 } }) client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.117 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.117 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 240, tv_nsec: 385169633 } client | 0.117 DEBUG [Client 732bc4e1eace5dc9368b] pn=9 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 46 client | TX -> Ack { largest_acknowledged: 14, ack_delay: 13, first_ack_range: 14, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.117 DEBUG ECN probing: sent 9 probes client | 0.117 DEBUG Setting timeout of 342.261777ms client | 0.132 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.132 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.132 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 240, tv_nsec: 400082487 } client | 0.132 DEBUG [Client 732bc4e1eace5dc9368b] pn=10 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, Ect0) len 47 client | TX -> Ack { largest_acknowledged: 14, ack_delay: 1877, first_ack_range: 14, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.132 DEBUG ECN probing: sent 10 probes client | 0.132 DEBUG ECN probing concluded with 10 probes sent client | 0.132 DEBUG Setting timeout of 327.345867ms client | 0.132 DEBUG Setting timeout of 327.33122ms client | 0.148 DEBUG [Client 732bc4e1eace5dc9368b] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.148 DEBUG Building Short dcid Some(CID [12]: aea40e581318c9a500666d6f) client | 0.148 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 240, tv_nsec: 415968832 } client | 0.148 DEBUG [Client 732bc4e1eace5dc9368b] pn=11 type=Short pri-path:aea40e581318c9a500666d6f 0.0.0.0:53029->193.167.100.100:443 Tos(Cs0, NotEct) len 47 client | TX -> Ack { largest_acknowledged: 14, ack_delay: 3863, first_ack_range: 14, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.148 DEBUG Setting timeout of 311.467006ms client | 0.148 DEBUG Setting timeout of 311.459923ms server | send_count:20, lost_count:0, tlp_count:0, recv_count:22, srtt:34469 early_data_flag:0, conn_err:0, ack_info:#11-0#0-0#0-0#v0429, path_info:0/1/0/a/0,1,1,1,0,0,0,0,0,0,0,26,26,22,10240,0,27,i32,4,34,47,0,10,123,0,4,34,47,0,11,139,0,4,33,46,40004,9,108,0,4,11,4,15,93,4,9,40000,16,109,4,737,80,14,69,0-2,0-2,0-0,0-0,0-0,0-34,0-13670,0-8348,0-20,0-22,0-0,0-0,0-0,0-0,0-0,0-0,0-0,0-0 client | 0.460 DEBUG [Client 732bc4e1eace5dc9368b] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 240, tv_nsec: 727510327 } } -> Closed(Application(0)) client | 0.460 INFO Closing timer expired client | 0.460 DEBUG Output::None client | 0.460 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.460 DEBUG Timer fired while closed client | 0.460 DEBUG Output::None client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-12-13 19:55:02,791 Using the client's key log file. 2025-12-13 19:55:02,800 2025-12-13 19:55:02,800 Using the client's key log file. 2025-12-13 19:55:02,800 Using the client's key log file. 2025-12-13 19:55:02,808 2025-12-13 19:55:02,809 Using the client's key log file. 2025-12-13 19:55:03,224 Check of downloaded files succeeded. 2025-12-13 19:55:03,642 Check of Retry succeeded. Token used: cdb220733470c2fd652eb1c6003c5ba23881817290bb83d2b5f4f088aaa0e6edbd84a86ab5