2025-04-30 08:42:58,103 Generated random file: rkghrrjdog of size: 1024 2025-04-30 08:42:58,103 Requests: https://server4:443/rkghrrjdog 2025-04-30 08:42:58,163 2025-04-30 08:42:58,163 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_x592fmv8/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_rd4omw0x/ DOWNLOADS=/tmp/download_xroxh5qt/ SERVER_LOGS=/tmp/logs_server_kdpott9a CLIENT_LOGS=/tmp/logs_client__wame_z7 SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/ngtcp2/ngtcp2-interop:latest REQUESTS="https://server4:443/rkghrrjdog" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 08:43:24,565 Container sim Recreate Container sim Recreated 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] client | Setting up routes... server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off client | Actual changes: client | tx-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 | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 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 handshake ']' 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 1.047375733s sim | Using scenario: simple-p2p --delay=750ms --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/rkghrrjdog ']' 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/rkghrrjdog client | ' client | ++ tee -i -a /logs/client.log client | 0.000 DEBUG Logging initialized client | 0.001 DEBUG Default socket send buffer size is 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:53637 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=2a17a928d55ca7e2 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 client | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 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:53637->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443] Path validated Instant { tv_sec: 242, tv_nsec: 144101059 } client | 0.001 DEBUG [Client 2a17a928d55ca7e2] client_start client | 0.001 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x55ac7c4c2270] state -> InProgress client | 0.002 DEBUG [Client 2a17a928d55ca7e2] State change from Init -> WaitInitial client | 0.002 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1213 client | 0.002 DEBUG [Client 2a17a928d55ca7e2] pn=0 type=Initial pri-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.002 DEBUG packet_sent this=0x55ac7c4d5970, pn=0, ps=1252 client | 0.002 DEBUG ECN probing: sent 1 probes client | 0.002 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=326 client | 0.002 DEBUG [Client 2a17a928d55ca7e2] pn=1 type=Initial pri-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.002 DEBUG [Client 2a17a928d55ca7e2] pad Initial from 366 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55ac7c4d5970, pn=1, ps=1252 client | 0.002 DEBUG ECN probing: sent 2 probes client | 0.002 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) 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 2a17a928d55ca7e2] delay duration 3.8999ms client | 0.002 DEBUG Setting timeout of 3.8999ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) 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 2a17a928d55ca7e2] delay duration 3.868993ms client | 0.002 DEBUG Setting timeout of 3.868993ms client | 0.007 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) 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 2a17a928d55ca7e2] delay duration 294.822042ms client | 0.007 DEBUG Setting timeout of 294.822042ms client | 0.303 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.303 DEBUG [Client 2a17a928d55ca7e2] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.303 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.303 DEBUG [Client 2a17a928d55ca7e2] Lost: EcnEct0 client | 0.303 DEBUG [Client 2a17a928d55ca7e2] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 0.303 INFO Lost crypto frame space=in offset=1213 length=326 client | 0.303 DEBUG [Client 2a17a928d55ca7e2] Lost: EcnEct0 client | 0.303 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.303 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) scid Some(CID [0]: ) client | 0.303 DEBUG CRYPTO for in offset=0, len=1213 client | 0.303 DEBUG [Client 2a17a928d55ca7e2] pn=2 type=Initial pri-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.303 DEBUG packet_sent this=0x55ac7c4d5970, pn=2, ps=1252 client | 0.303 DEBUG ECN probing: sent 3 probes client | 0.303 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.303 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) scid Some(CID [0]: ) client | 0.303 DEBUG CRYPTO for in offset=1213, len=326 client | 0.303 DEBUG [Client 2a17a928d55ca7e2] pn=3 type=Initial pri-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.304 DEBUG [Client 2a17a928d55ca7e2] pad Initial from 366 to PLPMTU 1252 client | 0.304 DEBUG packet_sent this=0x55ac7c4d5970, pn=3, ps=1252 client | 0.304 DEBUG ECN probing: sent 4 probes client | 0.304 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.304 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) scid Some(CID [0]: ) client | 0.304 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.304 DEBUG [Client 2a17a928d55ca7e2] delay duration 599.848908ms client | 0.304 DEBUG Setting timeout of 599.848908ms client | 0.904 DEBUG [LossRecovery] PTO timer fired for Initial client | 0.904 DEBUG [Client 2a17a928d55ca7e2] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.904 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.904 DEBUG [Client 2a17a928d55ca7e2] Lost: EcnEct0 client | 0.904 DEBUG ECN validation failed, all 3 initial marked packets were lost client | 0.904 DEBUG [Client 2a17a928d55ca7e2] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 }) client | 0.904 INFO Lost crypto frame space=in offset=1213 length=326 client | 0.904 DEBUG [Client 2a17a928d55ca7e2] Lost: EcnEct0 client | 0.904 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.904 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) scid Some(CID [0]: ) client | 0.904 DEBUG CRYPTO for in offset=0, len=1213 client | 0.905 DEBUG [Client 2a17a928d55ca7e2] pn=4 type=Initial pri-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.905 DEBUG packet_sent this=0x55ac7c4d5970, pn=4, ps=1252 client | 0.905 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.905 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) scid Some(CID [0]: ) client | 0.905 DEBUG CRYPTO for in offset=1213, len=326 client | 0.905 DEBUG [Client 2a17a928d55ca7e2] pn=5 type=Initial pri-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 366 client | TX -> Crypto { offset: 1213, len: 326 } client | 0.905 DEBUG [Client 2a17a928d55ca7e2] pad Initial from 366 to PLPMTU 1252 client | 0.905 DEBUG packet_sent this=0x55ac7c4d5970, pn=5, ps=1252 client | 0.905 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.905 DEBUG Building Initial dcid Some(CID [8]: 2a17a928d55ca7e2) scid Some(CID [0]: ) client | 0.905 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.905 DEBUG [Client 2a17a928d55ca7e2] delay duration 1.199924028s client | 0.905 DEBUG Setting timeout of 1.199924028s client | 1.510 DEBUG [Client 2a17a928d55ca7e2] pn=1393670795 type=Initial pri-path:2a17a928d55ca7e2 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 58 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 1.510 DEBUG [Client 2a17a928d55ca7e2] Rx ACK space=in, ranges=[0..=0] client | 1.510 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 client | 1.510 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6260/13772 ssthresh 18446744073709551615] slow start += 1252 client | 1.510 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6260/13772 ssthresh 18446744073709551615] on_packets_acked this=0x55ac7c4d5970, limited=0, bytes_in_flight=6260, cwnd=13772, state=SlowStart, new_acked=1252 client | 1.510 DEBUG Acked crypto frame space=in offset=0 length=1213 client | 1.510 DEBUG [Client 2a17a928d55ca7e2] Changing to use Server CID=a7a7088b108fbf3960a61370f6ddf14912da client | 1.510 DEBUG [Client 2a17a928d55ca7e2] State change from WaitInitial -> WaitVersion client | 1.510 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.510 DEBUG Building Initial dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.510 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.510 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.920221645s client | 1.510 DEBUG Setting timeout of 3.920221645s client | 1.510 WARN Unhandled event StateChange(WaitVersion) client | 1.510 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.510 DEBUG Building Initial dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.510 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.510 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.920174366s client | 1.510 DEBUG Setting timeout of 3.920174366s client | 1.512 DEBUG [Client 2a17a928d55ca7e2] pn=1393670796 type=Initial pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 152 client | -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | -> RX Crypto { offset: 0, len: 90 } client | 1.512 DEBUG [Client 2a17a928d55ca7e2] Rx ACK space=in, ranges=[0..=1] client | 1.512 DEBUG [LossRecovery] ACK for Initial - largest_acked=1 client | 1.512 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5008/15024 ssthresh 18446744073709551615] slow start += 1252 client | 1.512 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5008/15024 ssthresh 18446744073709551615] on_packets_acked this=0x55ac7c4d5970, limited=0, bytes_in_flight=5008, cwnd=15024, state=SlowStart, new_acked=1252 client | 1.512 DEBUG Acked crypto frame space=in offset=1213 length=326 client | 1.512 DEBUG Read Ok(90) bytes client | 1.512 DEBUG Write secret available for Handshake: SymKey [32]: 6eb78f6096c9539199139c52c13223bf849fe588cc1891705b130a5b683b7973 client | 1.512 DEBUG Read secret available for Handshake: SymKey [32]: c3ad2ee2518b4c306118fb52b714bbc0431c05dfc53a658524d4fe8912d5aaa1 client | 1.512 DEBUG [Agent 0x55ac7c4c2270] state -> InProgress client | 1.512 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 1.512 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 1.512 DEBUG [Crypto] Handshake keys installed client | 1.512 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 243, tv_nsec: 655110598 } client | 1.512 DEBUG [Client 2a17a928d55ca7e2] State change from WaitVersion -> Handshaking client | 1.512 DEBUG [Client 2a17a928d55ca7e2] pn=1393670795 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 759 client | -> RX Crypto { offset: 0, len: 706 } client | 1.512 DEBUG Read Ok(706) bytes client | 1.513 DEBUG [Agent 0x55ac7c4c2270] state -> AuthenticationPending client | 1.513 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 243, tv_nsec: 655110598 } client | 1.513 DEBUG saving datagram of 289 bytes client | 1.513 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Initial dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.513 DEBUG [Client 2a17a928d55ca7e2] pn=6 type=Initial pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 61 client | TX -> Ack { largest_acknowledged: 1393670796, ack_delay: 155, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 1.513 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.513 DEBUG [Client 2a17a928d55ca7e2] pn=0 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 121 client | TX -> Ack { largest_acknowledged: 1393670795, ack_delay: 155, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | 1.513 DEBUG [Client 2a17a928d55ca7e2] Drop packet number space in client | 1.513 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 1.513 WARN [LossRecovery] ignoring in-6 from dropped space client | 1.513 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.513 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772638849s client | 1.513 DEBUG Setting timeout of 3.772638849s client | 1.513 DEBUG StateChange(Handshaking) client | 1.513 WARN Cannot create stream ConnectionState client | 1.513 DEBUG [Client 2a17a928d55ca7e2] Authenticated Ok client | 1.513 DEBUG Write secret available for ApplicationData: SymKey [32]: aed8b9f381c03003bb098ef197c9d3b9845a3d10252879e90727c26d6a72bfb5 client | 1.513 DEBUG Read secret available for ApplicationData: SymKey [32]: c8d49a112ea3e221ef7550ac586153daf12283bace01f7fbb1efc14aa2df5506 client | 1.513 DEBUG [Agent 0x55ac7c4c2270] SSL_AuthCertificateComplete: Ok(()) client | 1.513 DEBUG [Agent 0x55ac7c4c2270] 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 | 1.513 DEBUG [Client 2a17a928d55ca7e2] TLS connection complete client | 1.513 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 1.513 DEBUG [Crypto] Application write key installed client | 1.513 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 1.513 DEBUG [Crypto] application read keys installed client | 1.513 DEBUG [Client 2a17a928d55ca7e2] State change from Handshaking -> Connected client | 1.513 INFO [Client 2a17a928d55ca7e2] Connection established client | 1.513 DEBUG [Client 2a17a928d55ca7e2] process saved for epoch ApplicationData client | 1.513 DEBUG [Client 2a17a928d55ca7e2] pn=1393670795 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 289 client | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [26, 182, 232, 0, 51, 134, 49, 99, 107, 151, 187, 105, 187, 128, 251, 32, 140, 112], stateless_reset_token: [230, 98, 254, 234, 52, 134, 94, 89, 252, 223, 187, 205, 179, 149, 211, 206] } client | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [217, 43, 86, 18, 245, 198, 61, 86, 44, 97, 167, 144, 162, 75, 83, 197, 131, 84], stateless_reset_token: [66, 81, 37, 250, 116, 101, 24, 130, 121, 191, 38, 72, 188, 128, 228, 85] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [35, 251, 40, 40, 87, 215, 42, 20, 104, 135, 124, 165, 222, 167, 110, 51, 132, 38], stateless_reset_token: [188, 94, 86, 179, 77, 116, 90, 151, 178, 147, 154, 110, 124, 114, 120, 59] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [230, 44, 14, 87, 213, 118, 155, 196, 231, 189, 81, 164, 250, 189, 39, 249, 185, 198], stateless_reset_token: [25, 69, 146, 203, 110, 55, 106, 63, 67, 13, 221, 153, 207, 249, 61, 247] } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [249, 65, 117, 167, 13, 56, 94, 161, 142, 40, 181, 81, 156, 166, 126, 234, 196, 149], stateless_reset_token: [127, 122, 57, 112, 117, 54, 64, 254, 108, 244, 219, 84, 122, 17, 34, 28] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [200, 22, 166, 102, 184, 111, 223, 156, 17, 232, 128, 58, 86, 129, 160, 75, 45, 131], stateless_reset_token: [188, 155, 79, 252, 202, 167, 220, 154, 151, 195, 250, 222, 52, 20, 43, 163] } client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [53, 234, 205, 60, 98, 94, 86, 101, 104, 45, 157, 23, 125, 189, 136, 75, 199, 13], stateless_reset_token: [247, 173, 101, 21, 170, 91, 85, 151, 1, 18, 56, 19, 110, 55, 62, 26] } client | -> RX Padding { len: 2 } client | 1.513 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 243, tv_nsec: 655110598 } client | 1.513 DEBUG stream BiDi creatable client | 1.513 INFO Created stream 0 for https://server4/rkghrrjdog client | 1.513 INFO Saving https://server4/rkghrrjdog to "/downloads/rkghrrjdog" client | 1.513 DEBUG StateChange(Connected) client | 1.514 DEBUG stream 0 writable client | 1.514 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.514 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.514 DEBUG CRYPTO for hs offset=0, len=36 client | 1.514 DEBUG [Client 2a17a928d55ca7e2] pn=1 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 83 client | TX -> Crypto { offset: 0, len: 36 } client | 1.514 DEBUG packet_sent this=0x55ac7c4d5970, pn=1, ps=83 client | 1.514 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 1.514 DEBUG [Client 2a17a928d55ca7e2] pn=0 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 155 client | TX -> Ack { largest_acknowledged: 1393670795, ack_delay: 234, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | 1.514 DEBUG packet_sent this=0x55ac7c4d5970, pn=0, ps=72 client | 1.514 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.514 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.514 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 1.514 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.514 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772617238s client | 1.514 DEBUG Setting timeout of 3.772617238s client | 1.807 WARN [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 1.807 DEBUG [Client 2a17a928d55ca7e2] pn=1393670796 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 759 client | -> RX Crypto { offset: 0, len: 706 } client | 1.807 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 243, tv_nsec: 949999913 } client | 1.807 DEBUG [Client 2a17a928d55ca7e2] pn=1393670796 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 289 client | -> RX Padding { len: 268 } client | 1.807 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.807 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.807 DEBUG CRYPTO for hs offset=0, len=36 client | 1.807 DEBUG [Client 2a17a928d55ca7e2] pn=2 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 98 client | TX -> Ack { largest_acknowledged: 1393670796, ack_delay: 15, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | TX -> Crypto { offset: 0, len: 36 } client | 1.807 DEBUG packet_sent this=0x55ac7c4d5970, pn=2, ps=98 client | 1.807 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 1.807 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.807 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.807 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 1.807 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.807 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772614172s client | 1.807 DEBUG Setting timeout of 3.772614172s client | 1.807 WARN [Client ...] Dropped received packet: Decryption failure; Total: 2 client | 1.807 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.807 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.807 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 1.807 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.807 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772564199s client | 1.807 DEBUG Setting timeout of 3.772564199s client | 1.807 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.807 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 1.807 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 1.807 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.807 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772523873s client | 1.807 DEBUG Setting timeout of 3.772523873s client | 2.107 WARN [Client ...] Dropped received packet: Decryption failure; Total: 3 client | 2.107 DEBUG [Client 2a17a928d55ca7e2] pn=1393670797 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 759 client | -> RX Crypto { offset: 0, len: 706 } client | 2.107 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 244, tv_nsec: 250439200 } client | 2.107 DEBUG [Client 2a17a928d55ca7e2] pn=1393670797 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 297 client | -> RX Ping client | -> RX Padding { len: 275 } client | 2.107 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 244, tv_nsec: 250439200 } client | 2.107 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 244, tv_nsec: 250439200 } client | 2.107 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.107 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.107 DEBUG CRYPTO for hs offset=0, len=36 client | 2.107 DEBUG [Client 2a17a928d55ca7e2] pn=3 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 98 client | TX -> Ack { largest_acknowledged: 1393670797, ack_delay: 14, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } client | TX -> Crypto { offset: 0, len: 36 } client | 2.107 DEBUG packet_sent this=0x55ac7c4d5970, pn=3, ps=98 client | 2.107 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.107 DEBUG [Client 2a17a928d55ca7e2] pn=1 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 149 client | TX -> Ack { largest_acknowledged: 1393670797, ack_delay: 14, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } client | 2.107 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.107 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.107 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.107 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.107 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772600698s client | 2.107 DEBUG Setting timeout of 3.772600698s client | 2.107 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.107 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.107 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.107 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.107 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772561865s client | 2.107 DEBUG Setting timeout of 3.772561865s client | 2.407 WARN [Client ...] Dropped received packet: Decryption failure; Total: 4 client | 2.407 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.473065097s client | 2.407 DEBUG Setting timeout of 3.473065097s client | 2.407 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.473016616s client | 2.407 DEBUG Setting timeout of 3.473016616s client | 2.407 WARN [Client ...] Dropped received packet: Decryption failure; Total: 5 client | 2.407 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.472521308s client | 2.407 DEBUG Setting timeout of 3.472521308s client | 2.407 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.472490951s client | 2.407 DEBUG Setting timeout of 3.472490951s client | 2.708 WARN [Client ...] Dropped received packet: Decryption failure; Total: 6 client | 2.708 DEBUG [Client 2a17a928d55ca7e2] pn=1393670798 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 759 client | -> RX Crypto { offset: 0, len: 706 } client | 2.708 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 244, tv_nsec: 851650553 } client | 2.708 DEBUG [Client 2a17a928d55ca7e2] pn=1393670798 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 297 client | -> RX Padding { len: 276 } client | 2.708 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.708 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.708 DEBUG CRYPTO for hs offset=0, len=36 client | 2.708 DEBUG [Client 2a17a928d55ca7e2] pn=4 type=Handshake pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 98 client | TX -> Ack { largest_acknowledged: 1393670798, ack_delay: 11, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } client | TX -> Crypto { offset: 0, len: 36 } client | 2.708 DEBUG packet_sent this=0x55ac7c4d5970, pn=4, ps=98 client | 2.708 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.708 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.708 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.708 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.708 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.708 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772652774s client | 2.708 DEBUG Setting timeout of 3.772652774s client | 2.708 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.708 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 2.708 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 2.708 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.708 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.772621496s client | 2.708 DEBUG Setting timeout of 3.772621496s client | 3.016 DEBUG [Client 2a17a928d55ca7e2] pn=1393670799 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 1406 client | -> RX Ping client | -> RX Padding { len: 1387 } client | 3.016 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 245, tv_nsec: 159894044 } client | 3.016 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 245, tv_nsec: 159894044 } client | 3.016 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.016 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 3.017 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.017 DEBUG [Client 2a17a928d55ca7e2] pn=2 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 51 client | TX -> Ack { largest_acknowledged: 1393670799, ack_delay: 9, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } client | 3.017 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.017 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 3.017 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.017 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.017 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.464385178s client | 3.017 DEBUG Setting timeout of 3.464385178s client | 3.017 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.017 DEBUG Building Handshake dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) scid Some(CID [0]: ) client | 3.017 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.017 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.017 DEBUG [Client 2a17a928d55ca7e2] delay duration 3.46433803s client | 3.017 DEBUG Setting timeout of 3.46433803s client | 3.017 DEBUG [Client 2a17a928d55ca7e2] pn=1393670800 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 1200 client | -> RX HandshakeDone client | -> RX NewToken { token: [54, 148, 52, 96, 59, 167, 20, 198, 175, 185, 103, 8, 106, 233, 185, 209, 245, 219, 108, 4, 85, 182, 161, 145, 245, 194, 62, 95, 47, 239, 66, 145, 16, 77, 31, 23, 125, 245, 140, 113, 160] } client | -> RX Crypto { offset: 0, len: 237 } client | -> RX Stream { stream_id: 0, offset: 0, len: 893, fin: false } client | 3.017 DEBUG [Client 2a17a928d55ca7e2] State change from Connected -> Confirmed client | 3.017 DEBUG PMTUD started with probe size 1380 client | 3.017 DEBUG [Client 2a17a928d55ca7e2] Drop packet number space hs client | 3.017 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 3.017 DEBUG [Client 2a17a928d55ca7e2] No preferred address to migrate to client | 3.017 DEBUG Read Ok(237) bytes client | 3.017 DEBUG [0x55ac7c4c2270] Got resumption token [908]: 02000633fae7ba13..0000000000000000 client | 3.017 DEBUG [Agent 0x55ac7c4c2270] 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 | 3.017 DEBUG setting max_stream_data to 262144 client | 3.017 DEBUG resumption token [1050]: 0000000145e4405c..0000000000000000 client | 3.017 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 245, tv_nsec: 180674507 } client | 3.017 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.017 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.017 DEBUG Sending PMTUD probe of size 1380, count 1 client | 3.017 DEBUG [Client 2a17a928d55ca7e2] pn=3 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 1352 client | TX -> Ping client | TX -> Padding { len: 1315 } client | 3.017 DEBUG packet_sent this=0x55ac7c4d5970, pn=3, ps=1352 client | 3.017 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG [Client 2a17a928d55ca7e2] delay duration 4.047517ms client | 3.018 DEBUG Setting timeout of 4.047517ms client | 3.018 WARN Unhandled event StateChange(Confirmed) client | 3.018 DEBUG stream BiDi creatable client | 3.018 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG [Client 2a17a928d55ca7e2] delay duration 4.0009ms client | 3.018 DEBUG Setting timeout of 4.0009ms client | 3.018 DEBUG [Client 2a17a928d55ca7e2] pn=1393670801 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, Ect0) len 1200 client | -> RX Stream { stream_id: 0, offset: 893, len: 131, fin: true } client | -> RX Padding { len: 1045 } client | 3.018 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 245, tv_nsec: 161647631 } client | 3.018 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.018 DEBUG [Client 2a17a928d55ca7e2] pn=4 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 51 client | TX -> Ack { largest_acknowledged: 1393670801, ack_delay: 4, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } client | 3.018 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG [Client 2a17a928d55ca7e2] delay duration 5.87076ms client | 3.018 DEBUG Setting timeout of 5.87076ms client | 3.018 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG [Client 2a17a928d55ca7e2] delay duration 5.813864ms client | 3.018 DEBUG Setting timeout of 5.813864ms client | 3.018 DEBUG [Client 2a17a928d55ca7e2] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 256, tv_nsec: 555109155 } } client | 3.018 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 256, tv_nsec: 555109155 } }) client | 3.018 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 3.018 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.018 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 245, tv_nsec: 161816367 } client | 3.018 DEBUG [Client 2a17a928d55ca7e2] pn=5 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 62 client | TX -> Ack { largest_acknowledged: 1393670801, ack_delay: 21, first_ack_range: 6, ack_ranges: [], 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 | 3.018 DEBUG Setting timeout of 11.393211255s client | 3.040 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.040 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 3.040 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 245, tv_nsec: 183091875 } client | 3.040 DEBUG [Client 2a17a928d55ca7e2] pn=6 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 63 client | TX -> Ack { largest_acknowledged: 1393670801, ack_delay: 2680, first_ack_range: 6, ack_ranges: [], 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 | 3.040 DEBUG Setting timeout of 11.371942941s client | 3.040 DEBUG Setting timeout of 11.371928804s client | 4.519 DEBUG [Client 2a17a928d55ca7e2] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.519 DEBUG Building Short dcid Some(CID [18]: a7a7088b108fbf3960a61370f6ddf14912da) client | 4.519 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 246, tv_nsec: 662337919 } client | 4.519 DEBUG [Client 2a17a928d55ca7e2] pn=7 type=Short pri-path:a7a7088b108fbf3960a61370f6ddf14912da 0.0.0.0:53637->193.167.100.100:443 IpTos(Cs0, NotEct) len 65 client | TX -> Ack { largest_acknowledged: 1393670801, ack_delay: 187586, first_ack_range: 6, ack_ranges: [], 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 | 4.519 DEBUG Setting timeout of 9.892648666s client | 4.519 DEBUG Setting timeout of 9.892632556s client | 14.413 DEBUG [Client 2a17a928d55ca7e2] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 256, tv_nsec: 555109155 } } -> Closed(Application(0)) client | 14.413 INFO Closing timer expired client | 14.413 DEBUG Output::None client | 14.413 WARN Unhandled event StateChange(Closed(Application(0))) client | 14.413 DEBUG Timer fired while closed client | 14.413 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-04-30 08:43:24,661 Using the client's key log file. 2025-04-30 08:43:24,670 2025-04-30 08:43:24,670 Using the client's key log file. 2025-04-30 08:43:24,670 Using the client's key log file. 2025-04-30 08:43:24,678 2025-04-30 08:43:24,679 Using the client's key log file. 2025-04-30 08:43:24,975 Check of downloaded files succeeded. 2025-04-30 08:43:26,181 Check of downloaded files succeeded.