2025-09-11 20:38:09,952 Generated random file: fpnrjrdupo of size: 5120 2025-09-11 20:38:09,953 Generated random file: cuhzazqirb of size: 10240 2025-09-11 20:38:09,953 Requests: https://server4:443/fpnrjrdupo https://server4:443/cuhzazqirb 2025-09-11 20:38:10,010 2025-09-11 20:38:10,010 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_1202h18d/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_1g_a7yhf/ DOWNLOADS=/tmp/download_h575zdo0/ SERVER_LOGS=/tmp/logs_server_m_pyln5z CLIENT_LOGS=/tmp/logs_client_tehxxbdx SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=litespeedtech/lsquic-qir:latest REQUESTS="https://server4:443/fpnrjrdupo https://server4:443/cuhzazqirb" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-11 20:38:22,507 Container client Recreate Container server Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 server | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off server | TEST_PARAMS: server | REQUESTS: '' client | Endpoint's IPv4 address is 193.167.0.100 server | paths: server | server: server | port: server | SERVER_PARAMS: client | Endpoint's IPv6 address is fd00:cafe:cafe::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 resumption ']' client | + '[' -n /logs/qlog/ ']' client | + case "$ROLE" in client | + /wait-for-it.sh sim:57832 -s -t 30 client | wait-for-it.sh: waiting 30 seconds for sim:57832 sim | server:443 is available after 501.351967ms sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), 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/fpnrjrdupo https://server4:443/cuhzazqirb' ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test resumption --qlog-dir /logs/qlog/ --output-dir /downloads https://server4:443/fpnrjrdupo 'https://server4:443/cuhzazqirb 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:45594 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=35423d1d64ce3ad3 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=6 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:45594->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:35423d1d64ce3ad3 0.0.0.0:45594->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:35423d1d64ce3ad3 0.0.0.0:45594->193.167.100.100:443] Path validated Instant { tv_sec: 407, tv_nsec: 746238432 } client | 0.001 DEBUG [Client 35423d1d64ce3ad3] client_start client | 0.001 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x55b8226df420] state -> InProgress client | 0.002 DEBUG [Client 35423d1d64ce3ad3] State change from Init -> WaitInitial client | 0.002 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 35423d1d64ce3ad3) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1213 client | 0.002 DEBUG [Client 35423d1d64ce3ad3] pn=6 type=Initial pri-path:35423d1d64ce3ad3 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.002 DEBUG packet_sent this=0x55b8226f2c60, pn=6, ps=1252 client | 0.002 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 35423d1d64ce3ad3) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=327 client | 0.002 DEBUG [Client 35423d1d64ce3ad3] pn=7 type=Initial pri-path:35423d1d64ce3ad3 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 367 client | TX -> Crypto { offset: 1213, len: 327 } client | 0.002 DEBUG [Client 35423d1d64ce3ad3] pad Initial from 367 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55b8226f2c60, pn=7, ps=1252 client | 0.002 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 35423d1d64ce3ad3) 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 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 35423d1d64ce3ad3) 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 35423d1d64ce3ad3] delay duration 3.882672ms client | 0.002 DEBUG Setting timeout of 3.882672ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 35423d1d64ce3ad3) 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 35423d1d64ce3ad3] delay duration 3.848578ms client | 0.002 DEBUG Setting timeout of 3.848578ms client | 0.007 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [8]: 35423d1d64ce3ad3) 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 35423d1d64ce3ad3] delay duration 294.3074ms client | 0.007 DEBUG Setting timeout of 294.3074ms client | 0.040 DEBUG [Client 35423d1d64ce3ad3] pn=0 type=Initial pri-path:35423d1d64ce3ad3 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 135 client | -> RX Ack { largest_acknowledged: 7, ack_delay: 111, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | 0.040 DEBUG [Client 35423d1d64ce3ad3] Rx ACK space=in, ranges=[6..=7] client | 0.040 DEBUG [recovery::Loss] ACK for Initial - largest_acked=7 client | 0.041 DEBUG on_packets_acked this=0x55b8226f2c60, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=2504 client | 0.041 DEBUG Acked crypto frame space=in offset=1213 length=327 client | 0.041 DEBUG Acked crypto frame space=in offset=0 length=1213 client | 0.041 DEBUG Write secret available for Handshake: SymKey [32]: 61b2a366592f89e02f76eed901662105665cef58e5968f95cb1e6f16fe536679 client | 0.041 DEBUG Read secret available for Handshake: SymKey [32]: e923c98ba571838b1e634d98612fe88dc97a2436ac3550c68f1ca7c21f790d5a client | 0.041 DEBUG [Agent 0x55b8226df420] 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: 407, tv_nsec: 786121314 } client | 0.041 DEBUG [Client 35423d1d64ce3ad3] Changing to use Server CID=9d81d021dab16781 client | 0.041 DEBUG [Client 35423d1d64ce3ad3] State change from WaitInitial -> Handshaking client | 0.041 DEBUG [Client 35423d1d64ce3ad3] pn=1 type=Handshake pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 1117 client | -> RX Crypto { offset: 0, len: 708 } client | -> RX Padding { len: 371 } client | 0.042 DEBUG [Agent 0x55b8226df420] state -> AuthenticationPending client | 0.042 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 407, tv_nsec: 786121314 } client | 0.042 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.042 DEBUG Building Initial dcid Some(CID [8]: 9d81d021dab16781) scid Some(CID [0]: ) client | 0.042 DEBUG [Client 35423d1d64ce3ad3] pn=8 type=Initial pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 44 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 174, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 0.042 DEBUG Building Handshake dcid Some(CID [8]: 9d81d021dab16781) scid Some(CID [0]: ) client | 0.042 DEBUG [Client 35423d1d64ce3ad3] pn=0 type=Handshake pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 87 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 174, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 0.042 DEBUG [Client 35423d1d64ce3ad3] Drop packet number space in client | 0.042 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 0.042 INFO [recovery::Loss] ignoring in-8 from dropped space client | 0.042 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.042 DEBUG Building Handshake dcid Some(CID [8]: 9d81d021dab16781) 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 35423d1d64ce3ad3] delay duration 117.929095ms client | 0.042 DEBUG Setting timeout of 117.929095ms client | 0.042 DEBUG StateChange(Handshaking) client | 0.042 WARN Cannot create stream ConnectionState client | 0.042 DEBUG [Client 35423d1d64ce3ad3] Authenticated Ok client | 0.042 DEBUG Write secret available for ApplicationData: SymKey [32]: cf952f172bc8d622744203d8292a9c55734ab6d722a53b7951e45506fc041630 client | 0.042 DEBUG Read secret available for ApplicationData: SymKey [32]: 4b501ef1833aa5d951549edaa10c2009942126ab64f669a0c74da7cece339daa client | 0.042 DEBUG [Agent 0x55b8226df420] SSL_AuthCertificateComplete: Ok(()) client | 0.042 DEBUG [Agent 0x55b8226df420] 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.042 DEBUG [Client 35423d1d64ce3ad3] TLS connection complete client | 0.042 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.042 DEBUG [Crypto] Application write key installed client | 0.042 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.042 DEBUG [Crypto] application read keys installed client | 0.042 DEBUG [Client 35423d1d64ce3ad3] State change from Handshaking -> Connected client | 0.042 INFO [Client 35423d1d64ce3ad3] Connection established client | 0.042 DEBUG stream BiDi creatable client | 0.042 INFO Created stream 0 for https://server4/fpnrjrdupo client | 0.042 INFO Saving https://server4/fpnrjrdupo to "/downloads/fpnrjrdupo" client | 0.042 DEBUG stream UniDi creatable client | 0.042 DEBUG StateChange(Connected) client | 0.042 DEBUG stream 0 writable client | 0.042 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.042 DEBUG Building Handshake dcid Some(CID [8]: 9d81d021dab16781) scid Some(CID [0]: ) client | 0.042 DEBUG CRYPTO for hs offset=0, len=36 client | 0.042 DEBUG [Client 35423d1d64ce3ad3] pn=1 type=Handshake pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 73 client | TX -> Crypto { offset: 0, len: 36 } client | 0.042 DEBUG packet_sent this=0x55b8226f2c60, pn=1, ps=73 client | 0.042 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.042 DEBUG [Client 35423d1d64ce3ad3] pn=0 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 126 client | TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10000, ignore_order: false } client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.042 DEBUG packet_sent this=0x55b8226f2c60, pn=0, ps=53 client | 0.042 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.042 DEBUG Building Handshake dcid Some(CID [8]: 9d81d021dab16781) scid Some(CID [0]: ) client | 0.042 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.042 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.042 DEBUG [Client 35423d1d64ce3ad3] delay duration 117.908326ms client | 0.042 DEBUG Setting timeout of 117.908326ms client | 0.073 DEBUG [Client 35423d1d64ce3ad3] pn=2 type=Handshake pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 39 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 5, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.073 DEBUG [Client 35423d1d64ce3ad3] Rx ACK space=hs, ranges=[0..=0] client | 0.073 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=0 client | 0.073 DEBUG on_packets_acked this=0x55b8226f2c60, limited=1, bytes_in_flight=126, cwnd=12520, state=SlowStart, new_acked=0 client | 0.073 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG Building Handshake dcid Some(CID [8]: 9d81d021dab16781) scid Some(CID [0]: ) client | 0.073 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.073 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG [Client 35423d1d64ce3ad3] delay duration 87.782619ms client | 0.073 DEBUG Setting timeout of 87.782619ms client | 0.073 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG Building Handshake dcid Some(CID [8]: 9d81d021dab16781) scid Some(CID [0]: ) client | 0.073 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.073 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.073 DEBUG [Client 35423d1d64ce3ad3] delay duration 87.760958ms client | 0.073 DEBUG Setting timeout of 87.760958ms client | 0.074 DEBUG [Client 35423d1d64ce3ad3] pn=3 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 595 client | -> RX Crypto { offset: 0, len: 572 } client | -> RX HandshakeDone client | 0.074 DEBUG [0x55b8226df420] Got resumption token [951]: 0200063e8c8404d0..19ee56cb5c064ab4 client | 0.074 DEBUG [0x55b8226df420] Got resumption token [951]: 0200063e8c8404d0..b8ea4865fcd81952 client | 0.074 DEBUG [Agent 0x55b8226df420] 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.074 DEBUG setting max_stream_data to 1048576 client | 0.074 DEBUG [Client 35423d1d64ce3ad3] State change from Connected -> Confirmed client | 0.074 DEBUG PMTUD started with probe size 1380 client | 0.074 DEBUG [Client 35423d1d64ce3ad3] Drop packet number space hs client | 0.074 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 0.074 DEBUG [Client 35423d1d64ce3ad3] No preferred address to migrate to client | 0.074 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 407, tv_nsec: 819809929 } client | 0.074 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.074 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.074 DEBUG [Client 35423d1d64ce3ad3] pn=1 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 21, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | TX -> Ping client | TX -> Padding { len: 1317 } client | 0.074 DEBUG packet_sent this=0x55b8226f2c60, pn=1, ps=1352 client | 0.074 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG ECN probing: sent 1 probes client | 0.074 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG [Client 35423d1d64ce3ad3] delay duration 142.922793ms client | 0.074 DEBUG Setting timeout of 142.922793ms client | 0.074 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 0.074 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG [Client 35423d1d64ce3ad3] delay duration 142.892316ms client | 0.074 DEBUG Setting timeout of 142.892316ms client | 0.074 DEBUG stream BiDi creatable client | 0.074 DEBUG stream UniDi creatable client | 0.074 WARN Unhandled event StateChange(Confirmed) client | 0.074 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.074 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.074 DEBUG [Client 35423d1d64ce3ad3] delay duration 142.872128ms client | 0.074 DEBUG Setting timeout of 142.872128ms client | 0.075 DEBUG [Client 35423d1d64ce3ad3] pn=5 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 9, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [240, 204, 26, 7, 44, 23, 181, 190], stateless_reset_token: [44, 73, 133, 173, 113, 39, 49, 164, 106, 81, 153, 165, 171, 33, 248, 57] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [241, 157, 240, 208, 58, 50, 247, 155], stateless_reset_token: [248, 144, 203, 146, 44, 183, 104, 156, 58, 73, 29, 61, 41, 192, 156, 28] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [82, 137, 179, 130, 71, 13, 50, 133], stateless_reset_token: [33, 10, 193, 201, 250, 86, 4, 138, 218, 88, 222, 50, 107, 223, 199, 215] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [106, 120, 153, 18, 249, 49, 166, 127], stateless_reset_token: [84, 65, 87, 188, 78, 209, 223, 120, 28, 97, 191, 251, 52, 7, 217, 96] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [232, 19, 156, 158, 241, 177, 31, 47], stateless_reset_token: [107, 100, 96, 185, 173, 237, 38, 99, 73, 146, 51, 71, 132, 166, 128, 15] } client | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [67, 22, 126, 84, 41, 194, 0, 222], stateless_reset_token: [185, 66, 176, 158, 104, 187, 43, 208, 196, 46, 68, 129, 146, 99, 184, 117] } client | -> RX NewToken { token: [1, 10, 193, 38, 172, 239, 20, 14, 232, 93, 51, 175, 178, 156, 18, 7, 162, 42, 54, 181, 176, 152, 156, 211, 232, 211, 248, 190, 40, 49, 85, 128, 98, 250, 183, 1, 134, 124, 22, 155, 141, 176] } client | -> RX Stream { stream_id: 0, offset: 0, len: >>1015, fin: false } client | 0.075 DEBUG [Client 35423d1d64ce3ad3] Rx ACK space=ap, ranges=[0..=0] client | 0.075 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=0 client | 0.075 DEBUG on_packets_acked this=0x55b8226f2c60, limited=1, bytes_in_flight=1352, cwnd=12520, state=SlowStart, new_acked=53 client | 0.075 DEBUG resumption token [1082]: 0000000126405100..b8ea4865fcd81952 client | 0.075 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 407, tv_nsec: 820890037 } client | 0.075 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.075 DEBUG [Client 35423d1d64ce3ad3] pn=2 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 36 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 9, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | 0.075 DEBUG ECN probing: sent 2 probes client | 0.075 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 35423d1d64ce3ad3] delay duration 112.924404ms client | 0.075 DEBUG Setting timeout of 112.924404ms client | 0.075 DEBUG stream 0 complete client | 0.075 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client 35423d1d64ce3ad3] delay duration 112.896862ms client | 0.075 DEBUG Setting timeout of 112.896862ms client | 0.076 DEBUG [Client 35423d1d64ce3ad3] pn=6 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Stream { stream_id: 0, offset: 1015, len: >>1230, fin: false } client | 0.076 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 407, tv_nsec: 841907677 } client | 0.076 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG [Client 35423d1d64ce3ad3] delay duration 19.977738ms client | 0.076 DEBUG Setting timeout of 19.977738ms client | 0.076 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG [Client 35423d1d64ce3ad3] delay duration 19.961688ms client | 0.076 DEBUG Setting timeout of 19.961688ms client | 0.077 DEBUG [Client 35423d1d64ce3ad3] pn=7 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Stream { stream_id: 0, offset: 2245, len: >>1230, fin: false } client | 0.077 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 407, tv_nsec: 822940215 } client | 0.077 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.077 DEBUG [Client 35423d1d64ce3ad3] pn=3 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 36 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 2, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } client | 0.077 DEBUG ECN probing: sent 3 probes client | 0.077 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG [Client 35423d1d64ce3ad3] delay duration 110.942954ms client | 0.077 DEBUG Setting timeout of 110.942954ms client | 0.077 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG [Client 35423d1d64ce3ad3] delay duration 110.918368ms client | 0.077 DEBUG Setting timeout of 110.918368ms client | 0.078 DEBUG [Client 35423d1d64ce3ad3] pn=8 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 1252 client | -> RX Stream { stream_id: 0, offset: 3475, len: >>1230, fin: false } client | 0.078 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 407, tv_nsec: 843957285 } client | 0.078 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG [Client 35423d1d64ce3ad3] delay duration 19.978249ms client | 0.078 DEBUG Setting timeout of 19.978249ms client | 0.078 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG [Client 35423d1d64ce3ad3] delay duration 19.961438ms client | 0.078 DEBUG Setting timeout of 19.961438ms client | 0.079 DEBUG [Client 35423d1d64ce3ad3] pn=9 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 439 client | -> RX Stream { stream_id: 0, offset: 4705, len: 415, fin: true } client | 0.079 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 407, tv_nsec: 824337281 } client | 0.079 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.079 DEBUG [Client 35423d1d64ce3ad3] pn=4 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 36 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 2, first_ack_range: 4, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } client | 0.079 DEBUG ECN probing: sent 4 probes client | 0.079 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG [Client 35423d1d64ce3ad3] delay duration 109.529778ms client | 0.079 DEBUG Setting timeout of 109.529778ms client | 0.079 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG [Client 35423d1d64ce3ad3] delay duration 109.478081ms client | 0.079 DEBUG Setting timeout of 109.478081ms client | 0.079 DEBUG [Client 35423d1d64ce3ad3] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 408, tv_nsec: 166457475 } } client | 0.079 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 408, tv_nsec: 166457475 } }) client | 0.079 DEBUG [Client 35423d1d64ce3ad3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG Building Short dcid Some(CID [8]: 9d81d021dab16781) client | 0.079 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 407, tv_nsec: 824517249 } client | 0.079 DEBUG [Client 35423d1d64ce3ad3] pn=5 type=Short pri-path:9d81d021dab16781 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 47 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 22, first_ack_range: 4, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.079 DEBUG ECN probing: sent 5 probes client | 0.079 DEBUG Setting timeout of 341.872829ms client | 0.422 DEBUG [Client 35423d1d64ce3ad3] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 408, tv_nsec: 166457475 } } -> Closed(Application(0)) client | 0.422 INFO Closing timer expired client | 0.422 DEBUG Output::None client | 0.422 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.422 DEBUG Timer fired while closed client | 0.422 DEBUG Output::None client | 0.422 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=45facc14583cb3f9e17a179fc24f73 client | 0.422 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=18 client | 0.422 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.422 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 0.422 DEBUG [unv-path 0.0.0.0:45594->193.167.100.100:443] Make permanent client | 0.422 DEBUG [unv-path:45facc14583cb3f9e17a179fc24f73 0.0.0.0:45594->193.167.100.100:443] set as primary path client | 0.422 DEBUG [pri-unv-path:45facc14583cb3f9e17a179fc24f73 0.0.0.0:45594->193.167.100.100:443] Path validated Instant { tv_sec: 408, tv_nsec: 167538664 } client | 0.422 INFO [Client 45facc14583cb3f9e17a179fc24f73] resumption token [1082]: 0000000126405100..b8ea4865fcd81952 client | 0.422 DEBUG Overwrite initial version Version1 ==> Version1 client | 0.422 DEBUG Overwrite initial version Version1 ==> Version1 client | 0.422 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] client_start client | 0.423 DEBUG Writing transport parameters, msg=1 client | 0.423 DEBUG Write secret available for ZeroRtt: SymKey [32]: 3863936d2850acdc40a3cb0936ce640f5da548152e41f8c4264d66d6f4063a27 client | 0.423 DEBUG [Agent 0x55b8227167a0] state -> InProgress client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] State change from Init -> WaitInitial client | 0.423 DEBUG Making Write ZeroRtt CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Enabled 0-RTT client | 0.423 DEBUG stream BiDi creatable client | 0.423 INFO Created stream 0 for https://server4/cuhzazqirb client | 0.423 INFO Saving https://server4/cuhzazqirb to "/downloads/cuhzazqirb" client | 0.423 DEBUG stream UniDi creatable client | 0.423 DEBUG StateChange(WaitInitial) client | 0.423 DEBUG stream 0 writable client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.423 DEBUG Building Initial dcid Some(CID [15]: 45facc14583cb3f9e17a179fc24f73) scid Some(CID [0]: ) client | 0.423 DEBUG CRYPTO for in offset=0, len=1164 client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=18 type=Initial pri-path:45facc14583cb3f9e17a179fc24f73 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1164 } client | 0.423 DEBUG packet_sent this=0x55b8226f2c60, pn=18, ps=1252 client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.423 DEBUG Building Initial dcid Some(CID [15]: 45facc14583cb3f9e17a179fc24f73) scid Some(CID [0]: ) client | 0.423 DEBUG CRYPTO for in offset=1164, len=683 client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=19 type=Initial pri-path:45facc14583cb3f9e17a179fc24f73 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 772 client | TX -> Crypto { offset: 1164, len: 683 } client | 0.423 DEBUG Building ZeroRtt dcid Some(CID [15]: 45facc14583cb3f9e17a179fc24f73) scid Some(CID [0]: ) client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=0 type=ZeroRtt pri-path:45facc14583cb3f9e17a179fc24f73 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 833 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.423 DEBUG packet_sent this=0x55b8226f2c60, pn=0, ps=61 client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pad Initial from 833 to PLPMTU 1252 client | 0.423 DEBUG packet_sent this=0x55b8226f2c60, pn=19, ps=1191 client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.423 DEBUG Building Initial dcid Some(CID [15]: 45facc14583cb3f9e17a179fc24f73) scid Some(CID [0]: ) client | 0.423 DEBUG Building ZeroRtt dcid Some(CID [15]: 45facc14583cb3f9e17a179fc24f73) scid Some(CID [0]: ) client | 0.423 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.423 DEBUG Building Initial dcid Some(CID [15]: 45facc14583cb3f9e17a179fc24f73) scid Some(CID [0]: ) client | 0.423 DEBUG Building ZeroRtt dcid Some(CID [15]: 45facc14583cb3f9e17a179fc24f73) scid Some(CID [0]: ) client | 0.423 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.423 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 113.883351ms client | 0.423 DEBUG Setting timeout of 113.883351ms client | 0.455 INFO [Client 45facc14583cb3f9e17a179fc24f73] received Retry client | 0.455 INFO [Client 45facc14583cb3f9e17a179fc24f73] Valid Retry received, token=009e597f3fbf84e269d13cc357d4fcae3d4f515886c4ac795f7527276964e113ae8a12085a0fb640ae943bb8c4f0b82d5323be8469933e02f0888d scid=e5f5e27bcda030d4 client | 0.455 INFO [pri-path:e5f5e27bcda030d4 0.0.0.0:45594->193.167.100.100:443] discarding a packet without an RTT estimate; guessing RTT=31.913361ms client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1164 }) client | 0.455 INFO Lost crypto frame space=in offset=0 length=1164 client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1164, length: 683 }) client | 0.455 INFO Lost crypto frame space=in offset=1164 length=683 client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Lost: Stream(Stream(RecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true })) client | 0.455 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=e5f5e27bcda030d4 client | 0.455 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.455 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.455 INFO [CryptoStates] Continue packet numbers for initial after retry (write is 0..0) client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG Building Initial dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG CRYPTO for in offset=0, len=1154 client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=20 type=Initial pri-path:e5f5e27bcda030d4 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1154 } client | 0.455 DEBUG packet_sent this=0x55b8226f2c60, pn=20, ps=1252 client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG Building Initial dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG CRYPTO for in offset=1154, len=693 client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=21 type=Initial pri-path:e5f5e27bcda030d4 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 792 client | TX -> Crypto { offset: 1154, len: 693 } client | 0.455 DEBUG Building ZeroRtt dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=1 type=ZeroRtt pri-path:e5f5e27bcda030d4 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 846 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 0.455 DEBUG packet_sent this=0x55b8226f2c60, pn=1, ps=54 client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pad Initial from 846 to PLPMTU 1252 client | 0.455 DEBUG packet_sent this=0x55b8226f2c60, pn=21, ps=1198 client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG Building Initial dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG Building ZeroRtt dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG Building Initial dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG Building ZeroRtt dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 95.623342ms client | 0.455 DEBUG Setting timeout of 95.623342ms client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG Building Initial dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG Building ZeroRtt dcid Some(CID [8]: e5f5e27bcda030d4) scid Some(CID [0]: ) client | 0.455 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.455 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 95.600369ms client | 0.455 DEBUG Setting timeout of 95.600369ms client | 0.487 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=0 type=Initial pri-path:e5f5e27bcda030d4 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 40 client | -> RX Ack { largest_acknowledged: 20, ack_delay: 28, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.487 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Rx ACK space=in, ranges=[20..=20] client | 0.487 DEBUG [recovery::Loss] ACK for Initial - largest_acked=20 client | 0.487 DEBUG on_packets_acked this=0x55b8226f2c60, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252 client | 0.487 DEBUG Acked crypto frame space=in offset=0 length=1154 client | 0.487 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Changing to use Server CID=c083e4bbc1bd9d77 client | 0.487 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] State change from WaitInitial -> WaitVersion client | 0.487 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.487 DEBUG Building Initial dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.487 DEBUG Building ZeroRtt dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.487 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.487 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 47.996684ms client | 0.487 DEBUG Setting timeout of 47.996684ms client | 0.487 WARN Unhandled event StateChange(WaitVersion) client | 0.487 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.487 DEBUG Building Initial dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.487 DEBUG Building ZeroRtt dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.487 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.487 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 47.965937ms client | 0.487 DEBUG Setting timeout of 47.965937ms client | 0.490 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=1 type=Initial pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 1200 client | -> RX Ack { largest_acknowledged: 21, ack_delay: 113, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 96 } client | -> RX Padding { len: 1059 } client | 0.490 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Rx ACK space=in, ranges=[20..=21] client | 0.490 DEBUG [recovery::Loss] ACK for Initial - largest_acked=21 client | 0.490 DEBUG on_packets_acked this=0x55b8226f2c60, limited=1, bytes_in_flight=54, cwnd=12520, state=SlowStart, new_acked=1198 client | 0.490 DEBUG Acked crypto frame space=in offset=1154 length=693 client | 0.490 DEBUG Write secret available for Handshake: SymKey [32]: a3a14e4adae18315ac4e3bd58a8b1a778d98838cf127abc41a1d7bf16765fffa client | 0.491 DEBUG Read secret available for Handshake: SymKey [32]: 38cb677a7d1f37227dcbe41b081093adb41cc791358bdf69c6d84fa51a0f0e2f client | 0.491 DEBUG [Agent 0x55b8227167a0] state -> InProgress client | 0.491 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.491 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.491 DEBUG [Crypto] Handshake keys installed client | 0.491 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 235910388 } client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] State change from WaitVersion -> Handshaking client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.491 DEBUG Building Initial dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=22 type=Initial pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 102 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 53, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | 0.491 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG Building ZeroRtt dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pad Initial from 102 to PLPMTU 1252 client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.491 DEBUG Building Initial dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG Building ZeroRtt dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.491 DEBUG Building Initial dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG Building ZeroRtt dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 35.866785ms client | 0.491 DEBUG Setting timeout of 35.866785ms client | 0.491 DEBUG StateChange(Handshaking) client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.491 DEBUG Building Initial dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG Building ZeroRtt dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.491 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 35.830086ms client | 0.491 DEBUG Setting timeout of 35.830086ms client | 0.491 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=2 type=Handshake pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 1111 client | -> RX Crypto { offset: 0, len: 182 } client | -> RX Padding { len: 891 } client | 0.491 DEBUG Write secret available for ApplicationData: SymKey [32]: 477c238965d876238b59a35c3c061e85993b6f823c8a89597409d071edf64856 client | 0.491 DEBUG Read secret available for ApplicationData: SymKey [32]: 9c52436127d4adb9380c390f9a86b438f4c875823970482476b75cfcaa26e998 client | 0.492 DEBUG [Agent 0x55b8227167a0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: true, early_data: true, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] TLS connection complete client | 0.492 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.492 DEBUG [Crypto] Application write key installed client | 0.492 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.492 DEBUG [Crypto] application read keys installed client | 0.492 DEBUG setting max_stream_data to 1048576 client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] State change from Handshaking -> Connected client | 0.492 INFO [Client 45facc14583cb3f9e17a179fc24f73] Connection established client | 0.492 DEBUG setting max_stream_data to 1048576 client | 0.492 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 236879685 } client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Initial dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.492 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.492 DEBUG CRYPTO for hs offset=0, len=36 client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=0 type=Handshake pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 81 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 58, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | TX -> Crypto { offset: 0, len: 36 } client | 0.492 DEBUG packet_sent this=0x55b8226f2c60, pn=0, ps=81 client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Drop packet number space in client | 0.492 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 0.492 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=2 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 114 client | TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10000, ignore_order: false } client | 0.492 DEBUG packet_sent this=0x55b8226f2c60, pn=2, ps=33 client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.492 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 71.47918ms client | 0.492 DEBUG Setting timeout of 71.47918ms client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=3 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect0) len 594 client | -> RX Crypto { offset: 0, len: 572 } client | 0.492 DEBUG [0x55b8227167a0] Got resumption token [951]: 0200063e8c840b2f..76e8c4933bca84ef client | 0.492 DEBUG [0x55b8227167a0] Got resumption token [951]: 0200063e8c840b2f..805944230a53e29e client | 0.492 DEBUG [Agent 0x55b8227167a0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: true, early_data: true, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 0.492 DEBUG setting max_stream_data to 1048576 client | 0.492 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 237477758 } client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.492 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=3 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 12, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.492 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 71.233579ms client | 0.492 DEBUG Setting timeout of 71.233579ms client | 0.492 DEBUG stream BiDi creatable client | 0.492 DEBUG stream UniDi creatable client | 0.492 DEBUG StateChange(Connected) client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.492 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.492 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 71.200688ms client | 0.492 DEBUG Setting timeout of 71.200688ms client | 0.493 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=4 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 1252 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 8, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [13, 37, 104, 234, 91, 149, 162, 25], stateless_reset_token: [253, 212, 70, 90, 252, 40, 168, 82, 50, 125, 252, 80, 218, 132, 100, 175] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [242, 147, 213, 101, 216, 117, 97, 151], stateless_reset_token: [123, 163, 230, 228, 113, 8, 59, 4, 157, 9, 248, 168, 2, 55, 120, 76] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [180, 126, 187, 124, 103, 26, 30, 163], stateless_reset_token: [135, 217, 177, 156, 3, 210, 208, 116, 5, 79, 57, 153, 33, 197, 101, 152] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [21, 40, 134, 205, 246, 3, 62, 47], stateless_reset_token: [20, 203, 170, 143, 195, 64, 86, 198, 226, 204, 1, 81, 39, 26, 50, 255] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [142, 243, 121, 196, 200, 51, 181, 149], stateless_reset_token: [191, 19, 117, 247, 60, 221, 212, 89, 89, 131, 65, 3, 46, 172, 170, 211] } client | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [52, 68, 127, 253, 138, 233, 223, 27], stateless_reset_token: [122, 164, 28, 104, 201, 232, 11, 76, 16, 48, 168, 58, 192, 35, 162, 193] } client | -> RX NewToken { token: [1, 141, 7, 151, 230, 222, 26, 246, 76, 60, 42, 223, 69, 121, 69, 80, 221, 6, 21, 66, 24, 123, 127, 175, 79, 142, 115, 165, 194, 89, 243, 255, 213, 67, 204, 246, 14, 131, 249, 49, 112, 21] } client | -> RX Stream { stream_id: 0, offset: 0, len: >>1015, fin: false } client | 0.493 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] Rx ACK space=ap, ranges=[1..=1] client | 0.493 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=1 client | 0.493 DEBUG on_packets_acked this=0x55b8226f2c60, limited=1, bytes_in_flight=114, cwnd=12520, state=SlowStart, new_acked=54 client | 0.493 DEBUG resumption token [1099]: 0000000120406200..805944230a53e29e client | 0.493 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 258386433 } client | 0.493 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.493 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.493 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.92528ms client | 0.493 DEBUG Setting timeout of 19.92528ms client | 0.493 DEBUG stream 0 complete client | 0.493 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.493 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.493 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.493 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.901415ms client | 0.493 DEBUG Setting timeout of 19.901415ms client | 0.494 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=5 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 1252 client | -> RX Stream { stream_id: 0, offset: 1015, len: >>1230, fin: false } client | 0.494 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 239414316 } client | 0.494 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.494 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.494 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=4 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 1, Ce: 0})) } client | 0.494 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.494 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.494 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 65.568515ms client | 0.494 DEBUG Setting timeout of 65.568515ms client | 0.494 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.494 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.494 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.494 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 65.53936ms client | 0.494 DEBUG Setting timeout of 65.53936ms client | 0.495 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=6 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 1252 client | -> RX Stream { stream_id: 0, offset: 2245, len: >>1230, fin: false } client | 0.495 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 260430894 } client | 0.495 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.495 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.495 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.974171ms client | 0.495 DEBUG Setting timeout of 19.974171ms client | 0.495 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.495 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.495 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.495 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.950858ms client | 0.495 DEBUG Setting timeout of 19.950858ms client | 0.496 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=7 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 1252 client | -> RX Stream { stream_id: 0, offset: 3475, len: >>1230, fin: false } client | 0.496 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 241454336 } client | 0.496 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.496 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.496 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=5 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 7, ack_delay: 6, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 4, Ect0: 1, Ce: 0})) } client | 0.496 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.496 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.496 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 63.500623ms client | 0.496 DEBUG Setting timeout of 63.500623ms client | 0.496 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.496 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.496 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.496 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 63.474885ms client | 0.496 DEBUG Setting timeout of 63.474885ms client | 0.497 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=8 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 1252 client | -> RX Stream { stream_id: 0, offset: 4705, len: >>1230, fin: false } client | 0.497 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 262479330 } client | 0.497 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.497 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.497 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.974642ms client | 0.497 DEBUG Setting timeout of 19.974642ms client | 0.497 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.497 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.497 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.497 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.951279ms client | 0.497 DEBUG Setting timeout of 19.951279ms client | 0.498 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=9 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 1252 client | -> RX Stream { stream_id: 0, offset: 5935, len: >>1230, fin: false } client | 0.498 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 243508352 } client | 0.498 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.498 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.498 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.498 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=6 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 9, ack_delay: 3, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 6, Ect0: 1, Ce: 0})) } client | 0.498 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.498 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.498 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.498 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.498 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 61.466447ms client | 0.498 DEBUG Setting timeout of 61.466447ms client | 0.498 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.498 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.498 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.498 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.498 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 61.432393ms client | 0.498 DEBUG Setting timeout of 61.432393ms client | 0.499 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=10 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 1252 client | -> RX Stream { stream_id: 0, offset: 7165, len: >>1230, fin: false } client | 0.499 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 264538462 } client | 0.499 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.499 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.499 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.499 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.499 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.974231ms client | 0.499 DEBUG Setting timeout of 19.974231ms client | 0.499 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.499 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.499 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.499 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.499 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.950848ms client | 0.499 DEBUG Setting timeout of 19.950848ms client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=11 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 1252 client | -> RX Stream { stream_id: 0, offset: 8395, len: >>1230, fin: false } client | 0.500 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 245564438 } client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.500 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=7 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 34 client | TX -> Ack { largest_acknowledged: 11, ack_delay: 3, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 8, Ect0: 1, Ce: 0})) } client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.500 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.500 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 59.425566ms client | 0.500 DEBUG Setting timeout of 59.425566ms client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.500 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.500 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 59.396972ms client | 0.500 DEBUG Setting timeout of 59.396972ms client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=12 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, Ect1) len 639 client | -> RX Stream { stream_id: 0, offset: 9625, len: 615, fin: true } client | 0.500 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 408, tv_nsec: 266097339 } client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.500 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.500 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.500 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.974703ms client | 0.500 DEBUG Setting timeout of 19.974703ms client | 0.501 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.501 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.501 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.501 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.501 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] delay duration 19.919449ms client | 0.501 DEBUG Setting timeout of 19.919449ms client | 0.501 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] State change from Connected -> Closing { error: Application(0), timeout: Instant { tv_sec: 408, tv_nsec: 449341278 } } client | 0.501 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 408, tv_nsec: 449341278 } }) client | 0.501 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.501 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.501 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 408, tv_nsec: 246207265 } client | 0.501 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=1 type=Handshake pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 47 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 1165, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.501 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.501 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 408, tv_nsec: 246207265 } client | 0.501 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=8 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 92 client | TX -> Ack { largest_acknowledged: 12, ack_delay: 13, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 9, Ect0: 1, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.501 DEBUG Setting timeout of 203.064482ms client | 0.523 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.523 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.523 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 408, tv_nsec: 268351225 } client | 0.523 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=2 type=Handshake pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 47 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 3933, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.523 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.523 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 408, tv_nsec: 268351225 } client | 0.523 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=9 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 93 client | TX -> Ack { largest_acknowledged: 12, ack_delay: 2781, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 9, Ect0: 1, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.523 DEBUG Setting timeout of 180.895665ms client | 0.523 DEBUG Setting timeout of 180.88735ms client | 0.531 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.531 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.531 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 408, tv_nsec: 276847835 } client | 0.531 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=3 type=Handshake pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 47 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 4996, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.531 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.531 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 408, tv_nsec: 276847835 } client | 0.531 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=10 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 93 client | TX -> Ack { largest_acknowledged: 12, ack_delay: 3843, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 9, Ect0: 1, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.531 DEBUG Setting timeout of 172.423872ms client | 0.531 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.531 DEBUG Building Handshake dcid Some(CID [8]: c083e4bbc1bd9d77) scid Some(CID [0]: ) client | 0.531 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 408, tv_nsec: 276926794 } client | 0.531 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=4 type=Handshake pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 47 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 5005, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" } client | 0.531 DEBUG Building Short dcid Some(CID [8]: c083e4bbc1bd9d77) client | 0.531 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 408, tv_nsec: 276926794 } client | 0.531 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] pn=11 type=Short pri-path:c083e4bbc1bd9d77 0.0.0.0:45594->193.167.100.100:443 Tos(Cs0, NotEct) len 93 client | TX -> Ack { largest_acknowledged: 12, ack_delay: 3853, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 9, Ect0: 1, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.531 DEBUG Setting timeout of 172.311401ms client | 0.531 DEBUG Setting timeout of 172.299789ms client | 0.705 DEBUG [Client 45facc14583cb3f9e17a179fc24f73] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 408, tv_nsec: 449341278 } } -> Closed(Application(0)) client | 0.705 INFO Closing timer expired client | 0.705 DEBUG Output::None client | 0.705 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.705 DEBUG Timer fired while closed client | 0.705 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 20:38:22,584 Using the client's key log file. 2025-09-11 20:38:22,592 2025-09-11 20:38:22,593 Using the client's key log file. 2025-09-11 20:38:22,593 Using the client's key log file. 2025-09-11 20:38:22,600 2025-09-11 20:38:22,601 Using the client's key log file. 2025-09-11 20:38:22,601 Using the client's key log file. 2025-09-11 20:38:23,227 Check of downloaded files succeeded.