2025-09-11 21:20:31,333 Generated random file: xclsdstqbc of size: 1024
2025-09-11 21:20:31,333 Requests: https://server4:443/xclsdstqbc
2025-09-11 21:20:31,389 
2025-09-11 21:20:31,390 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_n_706dhs/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_wkc5g0ig/ DOWNLOADS=/tmp/download_ux8i4yu2/ SERVER_LOGS=/tmp/logs_server_39ww3gir CLIENT_LOGS=/tmp/logs_client_mjrmuy7f 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/xclsdstqbc"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-09-11 21:20:43,849  Container sim  Recreate
 Container sim  Recreated
 Container client  Recreate
 Container server  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
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 handshake ']'
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  | ./demo_server -l d -L /logs/server.log -p 443 -D /www -k /logs/keys.log -i -M
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  | /logs/keys.log 4
server  | create ipv4 socket fd: 9
server  | create ipv6 socket fd: 10
sim     | server:443 is available after 501.011054ms
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  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads)
client  | + '[' https://server4:443/xclsdstqbc ']'
client  | + mapfile -d ' ' -t URLS
client  | + OPTIONS+=("${URLS[@]}")
client  | + RUST_LOG=debug
client  | + RUST_BACKTRACE=1
client  | + neqo-client --cc cubic --qns-test handshake --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/xclsdstqbc
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:33545 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=5e48b521a6381c22e4268dc8
client  | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=13
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:33545->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443] Path validated Instant { tv_sec: 405, tv_nsec: 417674370 }
client  | 0.001 DEBUG [Client 5e48b521a6381c22e4268dc8] client_start
client  | 0.002 DEBUG Writing transport parameters, msg=1
client  | 0.002 DEBUG [Agent 0x55b02a97c500] state -> InProgress
client  | 0.002 DEBUG [Client 5e48b521a6381c22e4268dc8] State change from Init -> WaitInitial
client  | 0.002 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=0, len=1209
client  | 0.002 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=13 type=Initial pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1209 }
client  | 0.002 DEBUG packet_sent this=0x55b02a98faf0, pn=13, ps=1252
client  | 0.002 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1209, len=331
client  | 0.002 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=14 type=Initial pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 375
client  |   TX -> Crypto { offset: 1209, len: 331 }
client  | 0.002 DEBUG [Client 5e48b521a6381c22e4268dc8] pad Initial from 375 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55b02a98faf0, pn=14, ps=1252
client  | 0.002 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) 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 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) 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 5e48b521a6381c22e4268dc8] delay duration 3.829052ms
client  | 0.002 DEBUG Setting timeout of 3.829052ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) 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 5e48b521a6381c22e4268dc8] delay duration 3.789459ms
client  | 0.002 DEBUG Setting timeout of 3.789459ms
client  | 0.007 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.007 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 294.309735ms
client  | 0.007 DEBUG Setting timeout of 294.309735ms
client  | 0.039 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=0 type=Initial pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 48
client  |   -> RX Ack { largest_acknowledged: 13, ack_delay: 65, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.039 DEBUG [Client 5e48b521a6381c22e4268dc8] Rx ACK space=in, ranges=[13..=13]
client  | 0.039 DEBUG [recovery::Loss] ACK for Initial - largest_acked=13
client  | 0.039 DEBUG on_packets_acked this=0x55b02a98faf0, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.039 DEBUG Acked crypto frame space=in offset=0 length=1209
client  | 0.039 DEBUG [Client 5e48b521a6381c22e4268dc8] Changing to use Server CID=5e48b521a6381c22e4268dc8
client  | 0.039 DEBUG [Client 5e48b521a6381c22e4268dc8] State change from WaitInitial -> WaitVersion
client  | 0.039 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) 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 5e48b521a6381c22e4268dc8] delay duration 75.204781ms
client  | 0.039 DEBUG Setting timeout of 75.204781ms
client  | 0.039 WARN Unhandled event StateChange(WaitVersion)
client  | 0.039 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) 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 5e48b521a6381c22e4268dc8] delay duration 75.165778ms
client  | 0.039 DEBUG Setting timeout of 75.165778ms
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=1 type=Initial pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 1200
client  |   -> RX Crypto { offset: 0, len: 90 }
client  |   -> RX Padding { len: 1064 }
client  | 0.041 DEBUG Write secret available for Handshake: SymKey [32]: ece3042a2562ac53efff401d3508ad7969e0293384c7698ec7b952e185adb9ce
client  | 0.041 DEBUG Read secret available for Handshake: SymKey [32]: 6aef38caaad7d02b6c220415e0a87eddedbc4317e1c8ef655650c72c54becac9
client  | 0.041 DEBUG [Agent 0x55b02a97c500] state -> InProgress
client  | 0.041 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.041 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.041 DEBUG [Crypto] Handshake keys installed
client  | 0.041 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 457716831 }
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] State change from WaitVersion -> Handshaking
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.041 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=15 type=Initial pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 68, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 0.041 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] pad Initial from 45 to PLPMTU 1252
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.041 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.041 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.041 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.041 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.041 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.041 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 72.89846ms
client  | 0.041 DEBUG Setting timeout of 72.89846ms
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=0 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 187
client  |   -> RX Crypto { offset: 0, len: 142 }
client  | 0.041 DEBUG [Agent 0x55b02a97c500] state -> InProgress
client  | 0.041 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 458401545 }
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.041 DEBUG Building Initial dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.041 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.041 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=0 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 43
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 4, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] Drop packet number space in
client  | 0.042 DEBUG [recovery::Loss] Reset loss recovery state for Initial
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.042 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 112.91371ms
client  | 0.042 DEBUG Setting timeout of 112.91371ms
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=1 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 517
client  |   -> RX Crypto { offset: 142, len: 471 }
client  | 0.042 DEBUG [Agent 0x55b02a97c500] state -> AuthenticationPending
client  | 0.042 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 458535955 }
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=1 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 43
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 17, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.042 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 112.641664ms
client  | 0.042 DEBUG Setting timeout of 112.641664ms
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=2 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 124
client  |   -> RX Crypto { offset: 613, len: 78 }
client  | 0.042 DEBUG [Agent 0x55b02a97c500] state -> AuthenticationPending
client  | 0.042 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 458829391 }
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.042 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=2 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 44
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 74, first_ack_range: 2, ack_ranges: [], ecn_count: None }
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 111.956319ms
client  | 0.043 DEBUG Setting timeout of 111.956319ms
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=3 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 81
client  |   -> RX Crypto { offset: 691, len: 36 }
client  | 0.043 DEBUG [Agent 0x55b02a97c500] state -> AuthenticationPending
client  | 0.043 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 459496241 }
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=3 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->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.043 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 111.833951ms
client  | 0.043 DEBUG Setting timeout of 111.833951ms
client  | 0.043 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=4 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 39
client  |   TX -> Ping
client  | 0.043 DEBUG packet_sent this=0x55b02a98faf0, pn=4, ps=41
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 112.955298ms
client  | 0.043 DEBUG Setting timeout of 112.955298ms
client  | 0.043 DEBUG StateChange(Handshaking)
client  | 0.043 WARN Cannot create stream ConnectionState
client  | 0.043 DEBUG stream BiDi creatable
client  | 0.043 WARN Cannot create stream ConnectionState
client  | 0.043 DEBUG stream UniDi creatable
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] Authenticated Ok
client  | 0.043 DEBUG Write secret available for ApplicationData: SymKey [32]: 606bff449713e2d453f33325768e67329c7f7041359201ada67bdcd9bba857b5
client  | 0.043 DEBUG Read secret available for ApplicationData: SymKey [32]: 0d428ae35f2bb4e5bcc81efd9f691f7e00345e49bb775ab2ecedfc02a4543ac7
client  | 0.043 DEBUG [Agent 0x55b02a97c500] SSL_AuthCertificateComplete: Ok(())
client  | 0.043 DEBUG [Agent 0x55b02a97c500] 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.043 DEBUG [Client 5e48b521a6381c22e4268dc8] TLS connection complete
client  | 0.043 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.043 DEBUG [Crypto] Application write key installed
client  | 0.043 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.043 DEBUG [Crypto] application read keys installed
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] State change from Handshaking -> Connected
client  | 0.043 INFO [Client 5e48b521a6381c22e4268dc8] Connection established
client  | 0.043 DEBUG stream BiDi creatable
client  | 0.043 INFO Created stream 0 for https://server4/xclsdstqbc
client  | 0.043 INFO Saving https://server4/xclsdstqbc to "/downloads/xclsdstqbc"
client  | 0.043 DEBUG stream UniDi creatable
client  | 0.043 DEBUG StateChange(Connected)
client  | 0.043 DEBUG stream 0 writable
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.043 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=5 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 77
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.043 DEBUG packet_sent this=0x55b02a98faf0, pn=5, ps=77
client  | 0.043 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=0 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 127
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 0.043 DEBUG packet_sent this=0x55b02a98faf0, pn=0, ps=50
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.043 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 112.919341ms
client  | 0.043 DEBUG Setting timeout of 112.919341ms
server  | xqc_hq_parse_req|247||hq recv CR LF|
client  | 0.074 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=4 type=Handshake pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->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.074 DEBUG [Client 5e48b521a6381c22e4268dc8] Rx ACK space=hs, ranges=[0..=4]
client  | 0.074 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=4
client  | 0.074 DEBUG on_packets_acked this=0x55b02a98faf0, limited=1, bytes_in_flight=127, cwnd=12520, state=SlowStart, new_acked=41
client  | 0.074 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.074 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.074 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.074 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 68.15487ms
client  | 0.074 DEBUG Setting timeout of 68.15487ms
client  | 0.074 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.074 DEBUG Building Handshake dcid Some(CID [12]: 5e48b521a6381c22e4268dc8) scid Some(CID [0]: )
client  | 0.074 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.074 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 68.116568ms
client  | 0.074 DEBUG Setting timeout of 68.116568ms
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=0 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 22
client  |   -> RX HandshakeDone
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] State change from Connected -> Confirmed
client  | 0.075 DEBUG PMTUD started with probe size 1380
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] Drop packet number space hs
client  | 0.075 DEBUG [recovery::Loss] Reset loss recovery state for Handshake
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] No preferred address to migrate to
client  | 0.075 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 512076260 }
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.075 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.075 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=1 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, Ect0) len 1352
client  |   TX -> Ping
client  |   TX -> Padding { len: 1321 }
client  | 0.075 DEBUG packet_sent this=0x55b02a98faf0, pn=1, ps=1352
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.075 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.075 DEBUG ECN probing: sent 1 probes
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.075 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 19.833911ms
client  | 0.075 DEBUG Setting timeout of 19.833911ms
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=1 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 32
client  |   -> RX NewToken { token: [0, 193, 167, 0, 100, 104, 204, 119, 161] }
client  | 0.075 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 492272074 }
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.075 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.075 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=2 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, Ect0) len 35
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 0.075 DEBUG ECN probing: sent 2 probes
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 124.116036ms
client  | 0.076 DEBUG Setting timeout of 124.116036ms
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=2 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 282
client  |   -> RX Crypto { offset: 0, len: 257 }
client  | 0.076 DEBUG [0x55b02a97c500] Got resumption token [921]: 0200063e8d1b822f..03b369936c4c2e88
client  | 0.076 DEBUG [Agent 0x55b02a97c500] 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 setting max_stream_data to 16777216
client  | 0.076 DEBUG resumption token [1029]: 0000000124405b00..03b369936c4c2e88
client  | 0.076 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 512592000 }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 19.85445ms
client  | 0.076 DEBUG Setting timeout of 19.85445ms
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=3 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 283
client  |   -> RX Crypto { offset: 257, len: 257 }
client  | 0.076 DEBUG [0x55b02a97c500] Got resumption token [921]: 0200063e8d1b8230..65d69eae819e08e2
client  | 0.076 DEBUG [Agent 0x55b02a97c500] 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 setting max_stream_data to 16777216
client  | 0.076 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 492758129 }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=3 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, Ect0) len 35
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 9, first_ack_range: 3, ack_ranges: [], ecn_count: None }
client  | 0.076 DEBUG ECN probing: sent 3 probes
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 123.688391ms
client  | 0.076 DEBUG Setting timeout of 123.688391ms
client  | 0.076 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 2
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 123.660028ms
client  | 0.076 DEBUG Setting timeout of 123.660028ms
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=4 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 53
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [3, 104, 179, 13, 44, 237, 8, 9, 32, 255, 49, 235], stateless_reset_token: [113, 74, 10, 22, 224, 16, 184, 97, 67, 151, 197, 201, 215, 154, 117, 83] }
client  | 0.076 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 512927985 }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 19.945329ms
client  | 0.076 DEBUG Setting timeout of 19.945329ms
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=5 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 53
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [54, 122, 103, 36, 61, 197, 228, 131, 54, 238, 16, 100], stateless_reset_token: [37, 236, 54, 182, 177, 30, 87, 152, 200, 236, 152, 137, 159, 133, 128, 231] }
client  | 0.076 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 493019936 }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=4 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->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.076 DEBUG ECN probing: sent 4 probes
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 123.459355ms
client  | 0.076 DEBUG Setting timeout of 123.459355ms
client  | 0.076 WARN Unhandled event StateChange(Confirmed)
client  | 0.076 DEBUG stream BiDi creatable
client  | 0.076 DEBUG stream UniDi creatable
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 123.432845ms
client  | 0.076 DEBUG Setting timeout of 123.432845ms
client  | 0.077 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=6 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, NotEct) len 1049
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
client  | 0.077 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 405, tv_nsec: 513749353 }
client  | 0.077 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 19.976146ms
client  | 0.077 DEBUG Setting timeout of 19.976146ms
client  | 0.077 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client 5e48b521a6381c22e4268dc8] delay duration 19.939698ms
client  | 0.077 DEBUG Setting timeout of 19.939698ms
client  | 0.077 DEBUG [Client 5e48b521a6381c22e4268dc8] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 405, tv_nsec: 867080271 } }
client  | 0.077 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 405, tv_nsec: 867080271 } })
client  | 0.077 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.077 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 405, tv_nsec: 493840412 }
client  | 0.077 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=5 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, Ect0) len 46
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 11, first_ack_range: 6, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.077 DEBUG ECN probing: sent 5 probes
client  | 0.077 DEBUG Setting timeout of 373.161002ms
client  | 0.100 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.100 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.100 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 405, tv_nsec: 517044378 }
client  | 0.100 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=6 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, Ect0) len 47
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 2911, first_ack_range: 6, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.100 DEBUG ECN probing: sent 6 probes
client  | 0.100 DEBUG Setting timeout of 349.965802ms
client  | 0.100 DEBUG Setting timeout of 349.954662ms
client  | 0.108 DEBUG [Client 5e48b521a6381c22e4268dc8] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.108 DEBUG Building Short dcid Some(CID [12]: 5e48b521a6381c22e4268dc8)
client  | 0.108 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 405, tv_nsec: 524689961 }
client  | 0.108 DEBUG [Client 5e48b521a6381c22e4268dc8] pn=7 type=Short pri-path:5e48b521a6381c22e4268dc8 0.0.0.0:33545->193.167.100.100:443 Tos(Cs0, Ect0) len 47
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 3867, first_ack_range: 6, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.108 DEBUG ECN probing: sent 7 probes
client  | 0.108 DEBUG Setting timeout of 342.324447ms
client  | 0.108 DEBUG Setting timeout of 342.312334ms
server  | send_count:12, lost_count:0, tlp_count:0, recv_count:16, srtt:32508 early_data_flag:0, conn_err:0, ack_info:#7-0#0-0#0-0#v0429, path_info:0/1/0/a/0,1,1,1,0,0,0,0,0,0,0,18,20,16,1024,0,17,i32,4,34,47,0,6,92,0,4,34,47,0,7,99,0,4,33,46,40004,5,69,0,4,1033,80,6,37,4,11,4,7,61,4,9,40000,8,69,0-2,0-2,0-0,0-0,0-0,0-32,0-4124,0-5694,0-12,0-16,0-0,0-0,0-0,0-0,0-0,0-0,0-0,0-0
client  | 0.451 DEBUG [Client 5e48b521a6381c22e4268dc8] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 405, tv_nsec: 867080271 } } -> Closed(Application(0))
client  | 0.451 INFO Closing timer expired
client  | 0.451 DEBUG Output::None
client  | 0.451 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 0.451 DEBUG Timer fired while closed
client  | 0.451 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-09-11 21:20:43,928 Using the client's key log file.
2025-09-11 21:20:43,937 
2025-09-11 21:20:43,937 Using the client's key log file.
2025-09-11 21:20:43,938 Using the client's key log file.
2025-09-11 21:20:43,945 
2025-09-11 21:20:43,945 Using the client's key log file.
2025-09-11 21:20:43,946 Using selector: EpollSelector
2025-09-11 21:20:44,163 Check of downloaded files succeeded.