2025-04-30 12:00:16,480 Generated random file: qftgnzmjdy of size: 5120 2025-04-30 12:00:16,480 Generated random file: zpknquyusk of size: 10240 2025-04-30 12:00:16,480 Requests: https://server6:443/qftgnzmjdy https://server6:443/zpknquyusk 2025-04-30 12:00:16,540 2025-04-30 12:00:16,541 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_cu_nlk92/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_kn4zdqk6/ DOWNLOADS=/tmp/download_8x15k_nw/ SERVER_LOGS=/tmp/logs_server_nwfkckac CLIENT_LOGS=/tmp/logs_client__0fx7dg3 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=martenseemann/quic-go-interop:latest REQUESTS="https://server6:443/qftgnzmjdy https://server6:443/zpknquyusk" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 12:00:28,900 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] 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 | 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 | 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 server | Using commit: e4bb2dbd5557c9417b30cf9fcd9032fd6fea5366 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 transfer ']' client | + '[' -n /logs/qlog/ ']' server | Running QUIC server. 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.038787514s sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet)tcpdump: , snapshot length 262144 bytes sim | listening on eth0, 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://server6:443/qftgnzmjdy https://server6:443/zpknquyusk' ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test transfer --qlog-dir /logs/qlog/ --output-dir /downloads https://server6:443/qftgnzmjdy 'https://server6:443/zpknquyusk 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: [::]:55439 -> [fd00:cafe:cafe:100::100]:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=aaed18706965896bf8ad1506da 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 fd00:cafe:cafe:100::100 has MTU 1500 client | 0.001 DEBUG [unv-path [::]:55439->[fd00:cafe:cafe:100::100]:443] Make permanent client | 0.001 DEBUG [unv-path:aaed18706965896bf8ad1506da [::]:55439->[fd00:cafe:cafe:100::100]:443] set as primary path client | 0.001 DEBUG [pri-unv-path:aaed18706965896bf8ad1506da [::]:55439->[fd00:cafe:cafe:100::100]:443] Path validated Instant { tv_sec: 581, tv_nsec: 481842868 } client | 0.001 DEBUG [Client aaed18706965896bf8ad1506da] client_start client | 0.001 DEBUG Writing transport parameters, msg=1 client | 0.001 DEBUG [Agent 0x56369007fed0] state -> InProgress client | 0.002 DEBUG [Client aaed18706965896bf8ad1506da] State change from Init -> WaitInitial client | 0.002 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: aaed18706965896bf8ad1506da) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1188 client | 0.002 DEBUG [Client aaed18706965896bf8ad1506da] pn=0 type=Initial pri-path:aaed18706965896bf8ad1506da [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1232 client | TX -> Crypto { offset: 0, len: 1188 } client | 0.002 DEBUG packet_sent this=0x5636900936d0, pn=0, ps=1232 client | 0.002 DEBUG ECN probing: sent 1 probes client | 0.002 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: aaed18706965896bf8ad1506da) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1188, len=351 client | 0.002 DEBUG [Client aaed18706965896bf8ad1506da] pn=1 type=Initial pri-path:aaed18706965896bf8ad1506da [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 396 client | TX -> Crypto { offset: 1188, len: 351 } client | 0.002 DEBUG [Client aaed18706965896bf8ad1506da] pad Initial from 396 to PLPMTU 1232 client | 0.002 DEBUG packet_sent this=0x5636900936d0, pn=1, ps=1232 client | 0.002 DEBUG ECN probing: sent 2 probes client | 0.002 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: aaed18706965896bf8ad1506da) 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 aaed18706965896bf8ad1506da] delay duration 3.905997ms client | 0.002 DEBUG Setting timeout of 3.905997ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [13]: aaed18706965896bf8ad1506da) 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 aaed18706965896bf8ad1506da] delay duration 3.876351ms client | 0.002 DEBUG Setting timeout of 3.876351ms client | 0.007 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG Building Initial dcid Some(CID [13]: aaed18706965896bf8ad1506da) scid Some(CID [0]: ) client | 0.007 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.007 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 294.8223ms client | 0.007 DEBUG Setting timeout of 294.8223ms client | 0.041 DEBUG [Client aaed18706965896bf8ad1506da] pn=0 type=Initial pri-path:aaed18706965896bf8ad1506da [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 40 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 | 0.041 DEBUG [Client aaed18706965896bf8ad1506da] Rx ACK space=in, ranges=[0..=0] client | 0.041 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 client | 0.041 DEBUG on_packets_acked this=0x5636900936d0, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232 client | 0.041 DEBUG Acked crypto frame space=in offset=0 length=1188 client | 0.041 DEBUG [Client aaed18706965896bf8ad1506da] Changing to use Server CID=9aacc1fa client | 0.041 DEBUG [Client aaed18706965896bf8ad1506da] State change from WaitInitial -> WaitVersion client | 0.041 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.041 DEBUG Building Initial dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.041 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.041 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 79.262018ms client | 0.041 DEBUG Setting timeout of 79.262018ms client | 0.041 WARN Unhandled event StateChange(WaitVersion) client | 0.041 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.041 DEBUG Building Initial dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.041 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.041 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 79.233845ms client | 0.041 DEBUG Setting timeout of 79.233845ms client | 0.042 DEBUG [Client aaed18706965896bf8ad1506da] pn=1 type=Initial pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 389 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 Padding { len: 255 } client | -> RX Crypto { offset: 0, len: 90 } client | 0.042 DEBUG [Client aaed18706965896bf8ad1506da] Rx ACK space=in, ranges=[0..=1] client | 0.042 DEBUG [LossRecovery] ACK for Initial - largest_acked=1 client | 0.042 DEBUG on_packets_acked this=0x5636900936d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232 client | 0.042 DEBUG Acked crypto frame space=in offset=1188 length=351 client | 0.042 DEBUG Read Ok(90) bytes client | 0.042 DEBUG Write secret available for Handshake: SymKey [32]: 14ea86c91567bf34beb49ef6dd6f77be583496d1832352ccd8a0c5d16568e390 client | 0.042 DEBUG Read secret available for Handshake: SymKey [32]: 1441fb583e378322198745130e075bdd62b833e3e74ffc54bae488f7832cf57e client | 0.043 DEBUG [Agent 0x56369007fed0] state -> InProgress client | 0.043 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 client | 0.043 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 client | 0.043 DEBUG [Crypto] Handshake keys installed client | 0.043 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 523452736 } client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] State change from WaitVersion -> Handshaking client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] pn=0 type=Handshake pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 752 client | -> RX Crypto { offset: 0, len: 717 } client | 0.043 DEBUG Read Ok(717) bytes client | 0.043 DEBUG [Agent 0x56369007fed0] state -> AuthenticationPending client | 0.043 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 523452736 } client | 0.043 DEBUG saving datagram of 139 bytes client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.043 DEBUG Building Initial dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] pn=2 type=Initial pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 37 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 140, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 0.043 DEBUG Building Handshake dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] pn=0 type=Handshake pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 73 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 140, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] Drop packet number space in client | 0.043 DEBUG [LossRecovery] Reset loss recovery state for Initial client | 0.043 WARN [LossRecovery] ignoring in-2 from dropped space client | 0.043 DEBUG ECN probing: sent 3 probes client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.043 DEBUG Building Handshake dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 99.781121ms client | 0.043 DEBUG Setting timeout of 99.781121ms client | 0.043 DEBUG StateChange(Handshaking) client | 0.043 WARN Cannot create stream ConnectionState client | 0.043 DEBUG [Client aaed18706965896bf8ad1506da] Authenticated Ok client | 0.043 DEBUG Write secret available for ApplicationData: SymKey [32]: 0e012d5fddae21449cf33cde8a528f100f6b2216d57b7305b16c761448904d60 client | 0.043 DEBUG Read secret available for ApplicationData: SymKey [32]: 08ea162a0e0e9904ab5a29c517340773f78282bae5f4d7a7b77d79c9148b1f4d client | 0.044 DEBUG [Agent 0x56369007fed0] SSL_AuthCertificateComplete: Ok(()) client | 0.044 DEBUG [Agent 0x56369007fed0] 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.044 DEBUG [Client aaed18706965896bf8ad1506da] TLS connection complete client | 0.044 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 0.044 DEBUG [Crypto] Application write key installed client | 0.044 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 client | 0.044 DEBUG [Crypto] application read keys installed client | 0.044 DEBUG [Client aaed18706965896bf8ad1506da] State change from Handshaking -> Connected client | 0.044 INFO [Client aaed18706965896bf8ad1506da] Connection established client | 0.044 DEBUG [Client aaed18706965896bf8ad1506da] process saved for epoch ApplicationData client | 0.044 DEBUG [Client aaed18706965896bf8ad1506da] pn=0 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 139 client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [35, 229, 77, 226], stateless_reset_token: [130, 164, 226, 56, 97, 110, 24, 90, 48, 188, 26, 156, 8, 100, 104, 181] } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [74, 13, 58, 7], stateless_reset_token: [14, 238, 104, 184, 23, 211, 37, 201, 69, 100, 104, 112, 166, 234, 184, 144] } client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [154, 195, 126, 206], stateless_reset_token: [4, 44, 124, 6, 141, 174, 183, 234, 182, 160, 168, 184, 106, 94, 253, 238] } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [90, 48, 124, 110], stateless_reset_token: [85, 180, 224, 79, 64, 123, 24, 29, 22, 30, 104, 36, 192, 33, 21, 166] } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [130, 237, 56, 159], stateless_reset_token: [9, 207, 100, 135, 66, 158, 218, 16, 130, 123, 117, 44, 171, 30, 187, 26] } client | 0.044 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 543452736 } client | 0.044 DEBUG stream BiDi creatable client | 0.044 INFO Created stream 0 for https://server6/qftgnzmjdy client | 0.044 INFO Saving https://server6/qftgnzmjdy to "/downloads/qftgnzmjdy" client | 0.044 INFO Created stream 4 for https://server6/zpknquyusk client | 0.044 INFO Saving https://server6/zpknquyusk to "/downloads/zpknquyusk" client | 0.044 DEBUG stream UniDi creatable client | 0.044 DEBUG StateChange(Connected) client | 0.044 DEBUG stream 0 writable client | 0.044 DEBUG stream 4 writable client | 0.044 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.044 DEBUG Building Handshake dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.044 DEBUG CRYPTO for hs offset=0, len=36 client | 0.044 DEBUG [Client aaed18706965896bf8ad1506da] pn=1 type=Handshake pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 69 client | TX -> Crypto { offset: 0, len: 36 } client | 0.044 DEBUG packet_sent this=0x5636900936d0, pn=1, ps=69 client | 0.044 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.044 DEBUG [Client aaed18706965896bf8ad1506da] pn=0 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 131 client | TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true } client | TX -> Stream { stream_id: 4, offset: 0, len: 17, fin: true } client | 0.044 DEBUG packet_sent this=0x5636900936d0, pn=0, ps=62 client | 0.044 DEBUG ECN probing: sent 4 probes client | 0.044 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.044 DEBUG Building Handshake dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.044 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.044 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.044 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 18.177729ms client | 0.044 DEBUG Setting timeout of 18.177729ms client | 0.063 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.063 DEBUG Building Handshake dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.063 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.063 DEBUG [Client aaed18706965896bf8ad1506da] pn=1 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 28 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 2615, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 0.063 DEBUG ECN probing: sent 5 probes client | 0.063 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.063 DEBUG Building Handshake dcid Some(CID [4]: 9aacc1fa) scid Some(CID [0]: ) client | 0.063 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.063 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.063 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 80.610867ms client | 0.063 DEBUG Setting timeout of 80.610867ms client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] pn=1 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 259 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 17, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } client | -> RX HandshakeDone client | -> RX NewToken { token: [116, 102, 11, 147, 40, 238, 199, 158, 64, 57, 129, 96, 6, 237, 117, 114, 109, 45, 39, 247, 212, 101, 198, 25, 126, 235, 110, 6, 235, 114, 218, 9, 137, 253, 79, 103, 247, 29, 170, 80, 170, 84, 123, 10, 119, 213, 94, 121, 88, 245, 15, 202, 159, 125, 137, 166, 69, 31, 155, 250, 51, 106, 166, 253, 131, 168, 180, 149, 200, 160, 19, 252, 83, 211, 174, 60, 27, 95, 207, 42, 169, 30, 39, 188, 145, 134] } client | -> RX Crypto { offset: 0, len: 138 } client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] Rx ACK space=ap, ranges=[0..=0] client | 0.075 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 client | 0.075 DEBUG on_packets_acked this=0x5636900936d0, limited=1, bytes_in_flight=69, cwnd=12320, state=SlowStart, new_acked=62 client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] State change from Connected -> Confirmed client | 0.075 DEBUG PMTUD started with probe size 1380 client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] Drop packet number space hs client | 0.075 DEBUG [LossRecovery] Reset loss recovery state for Handshake client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] No preferred address to migrate to client | 0.075 DEBUG Read Ok(138) bytes client | 0.075 DEBUG [0x56369007fed0] Got resumption token [817]: 02000633fda92b75..ae08cb2fa1f74315 client | 0.075 DEBUG [Agent 0x56369007fed0] 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.075 DEBUG setting max_stream_data to 524288 client | 0.075 DEBUG setting max_stream_data to 524288 client | 0.075 DEBUG resumption token [999]: 0000000126405700..ae08cb2fa1f74315 client | 0.075 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 576171966 } client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.075 DEBUG Sending PMTUD probe of size 1380, count 1 client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] pn=2 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1332 client | TX -> Ping client | TX -> Padding { len: 1309 } client | 0.075 DEBUG packet_sent this=0x5636900936d0, pn=2, ps=1332 client | 0.075 DEBUG ECN probing: sent 6 probes client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.706461ms client | 0.075 DEBUG Setting timeout of 19.706461ms client | 0.075 DEBUG stream 0 complete client | 0.075 DEBUG stream 4 complete client | 0.075 WARN Unhandled event StateChange(Confirmed) client | 0.075 DEBUG stream BiDi creatable client | 0.075 DEBUG stream UniDi creatable client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.075 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.075 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.666186ms client | 0.075 DEBUG Setting timeout of 19.666186ms client | 0.076 DEBUG [Client aaed18706965896bf8ad1506da] pn=2 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 4, offset: 0, len: >>1259, fin: false } client | 0.076 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 557490177 } client | 0.076 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.076 DEBUG [Client aaed18706965896bf8ad1506da] pn=3 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 30 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } client | 0.076 DEBUG ECN probing: sent 7 probes client | 0.076 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 117.114231ms client | 0.076 DEBUG Setting timeout of 117.114231ms client | 0.076 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.076 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 117.092079ms client | 0.076 DEBUG Setting timeout of 117.092079ms client | 0.077 DEBUG [Client aaed18706965896bf8ad1506da] pn=3 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 4, offset: 1259, len: >>1257, fin: false } client | 0.077 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 578553212 } client | 0.077 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.973009ms client | 0.077 DEBUG Setting timeout of 19.973009ms client | 0.077 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.077 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.94652ms client | 0.077 DEBUG Setting timeout of 19.94652ms client | 0.078 DEBUG [Client aaed18706965896bf8ad1506da] pn=4 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 4, offset: 2516, len: >>1257, fin: false } client | 0.078 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 559614178 } client | 0.078 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.078 DEBUG [Client aaed18706965896bf8ad1506da] pn=4 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 30 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 3, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } client | 0.078 DEBUG ECN probing: sent 8 probes client | 0.078 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 114.96856ms client | 0.078 DEBUG Setting timeout of 114.96856ms client | 0.078 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.078 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 114.94161ms client | 0.078 DEBUG Setting timeout of 114.94161ms client | 0.079 DEBUG [Client aaed18706965896bf8ad1506da] pn=5 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 0, offset: 0, len: >>1259, fin: false } client | 0.079 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 580626552 } client | 0.079 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.967609ms client | 0.079 DEBUG Setting timeout of 19.967609ms client | 0.079 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.079 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.945107ms client | 0.079 DEBUG Setting timeout of 19.945107ms client | 0.080 DEBUG [Client aaed18706965896bf8ad1506da] pn=6 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 4, offset: 3773, len: >>1257, fin: false } client | 0.081 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 561736700 } client | 0.081 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.081 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.081 DEBUG [Client aaed18706965896bf8ad1506da] pn=5 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 30 client | TX -> Ack { largest_acknowledged: 6, ack_delay: 5, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } client | 0.081 DEBUG ECN probing: sent 9 probes client | 0.081 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.081 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.081 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.081 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 112.840267ms client | 0.081 DEBUG Setting timeout of 112.840267ms client | 0.081 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.081 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.081 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.081 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 112.773522ms client | 0.081 DEBUG Setting timeout of 112.773522ms client | 0.082 DEBUG [Client aaed18706965896bf8ad1506da] pn=7 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 0, offset: 1259, len: >>1257, fin: false } client | 0.082 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 582809989 } client | 0.082 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.082 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.082 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.082 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.978801ms client | 0.082 DEBUG Setting timeout of 19.978801ms client | 0.082 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.082 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.082 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.082 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.964754ms client | 0.082 DEBUG Setting timeout of 19.964754ms client | 0.083 DEBUG [Client aaed18706965896bf8ad1506da] pn=8 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 4, offset: 5030, len: >>1257, fin: false } client | 0.083 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 563865584 } client | 0.083 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.083 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.083 DEBUG [Client aaed18706965896bf8ad1506da] pn=6 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 30 client | TX -> Ack { largest_acknowledged: 8, ack_delay: 2, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } client | 0.083 DEBUG ECN probing: sent 10 probes client | 0.083 DEBUG ECN probing concluded with 10 probes sent client | 0.083 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.083 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.083 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.083 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 110.748152ms client | 0.083 DEBUG Setting timeout of 110.748152ms client | 0.083 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.083 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.083 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.083 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 110.720621ms client | 0.083 DEBUG Setting timeout of 110.720621ms client | 0.084 DEBUG [Client aaed18706965896bf8ad1506da] pn=9 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 0, offset: 2516, len: >>1257, fin: false } client | 0.084 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 584928112 } client | 0.084 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.084 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.084 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.084 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.978319ms client | 0.084 DEBUG Setting timeout of 19.978319ms client | 0.084 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.084 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.084 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.084 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.964423ms client | 0.084 DEBUG Setting timeout of 19.964423ms client | 0.085 DEBUG [Client aaed18706965896bf8ad1506da] pn=10 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1280 client | -> RX Stream { stream_id: 4, offset: 6287, len: >>1257, fin: false } client | 0.085 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 565992254 } client | 0.085 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.085 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.085 DEBUG [Client aaed18706965896bf8ad1506da] pn=7 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 30 client | TX -> Ack { largest_acknowledged: 10, ack_delay: 4, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) } client | 0.085 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.085 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.085 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.085 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 108.598489ms client | 0.085 DEBUG Setting timeout of 108.598489ms client | 0.085 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.085 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.085 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.085 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 108.573171ms client | 0.085 DEBUG Setting timeout of 108.573171ms client | 0.086 DEBUG [Client aaed18706965896bf8ad1506da] pn=11 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 1280 client | -> RX Stream { stream_id: 0, offset: 3773, len: >>1257, fin: false } client | 0.086 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 587056345 } client | 0.086 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.086 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.086 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.086 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.97886ms client | 0.086 DEBUG Setting timeout of 19.97886ms client | 0.086 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.086 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.086 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.086 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.965004ms client | 0.086 DEBUG Setting timeout of 19.965004ms client | 0.087 DEBUG [Client aaed18706965896bf8ad1506da] pn=12 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 1280 client | -> RX Stream { stream_id: 4, offset: 7544, len: >>1257, fin: false } client | 0.087 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 568119485 } client | 0.087 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.087 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.087 DEBUG [Client aaed18706965896bf8ad1506da] pn=8 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 30 client | TX -> Ack { largest_acknowledged: 12, ack_delay: 2, first_ack_range: 12, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) } client | 0.087 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.087 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.087 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.087 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 106.491005ms client | 0.087 DEBUG Setting timeout of 106.491005ms client | 0.087 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.087 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.087 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.087 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 106.466208ms client | 0.087 DEBUG Setting timeout of 106.466208ms client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] pn=13 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 1280 client | -> RX Stream { stream_id: 0, offset: 5030, len: 90, fin: true } client | -> RX Stream { stream_id: 4, offset: 8801, len: >>1161, fin: false } client | 0.088 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 589189617 } client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.088 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.976075ms client | 0.088 DEBUG Setting timeout of 19.976075ms client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.088 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 19.932844ms client | 0.088 DEBUG Setting timeout of 19.932844ms client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] pn=14 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 301 client | -> RX Stream { stream_id: 4, offset: 9962, len: >>278, fin: true } client | 0.088 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 581, tv_nsec: 569499407 } client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] pn=9 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 30 client | TX -> Ack { largest_acknowledged: 14, ack_delay: 3, first_ack_range: 14, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) } client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.088 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 105.100703ms client | 0.088 DEBUG Setting timeout of 105.100703ms client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.088 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] delay duration 105.029109ms client | 0.088 DEBUG Setting timeout of 105.029109ms client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 581, tv_nsec: 924594046 } } client | 0.088 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 581, tv_nsec: 924594046 } }) client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.088 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.088 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 581, tv_nsec: 569675707 } client | 0.088 DEBUG [Client aaed18706965896bf8ad1506da] pn=10 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 41 client | TX -> Ack { largest_acknowledged: 14, ack_delay: 22, first_ack_range: 14, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.088 DEBUG Setting timeout of 354.858807ms client | 0.119 DEBUG [Client aaed18706965896bf8ad1506da] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } client | 0.119 DEBUG Building Short dcid Some(CID [4]: 9aacc1fa) client | 0.119 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 581, tv_nsec: 600105709 } client | 0.119 DEBUG [Client aaed18706965896bf8ad1506da] pn=11 type=Short pri-path:9aacc1fa [::]:55439->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 42 client | TX -> Ack { largest_acknowledged: 14, ack_delay: 3825, first_ack_range: 14, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 0.119 DEBUG Setting timeout of 324.417324ms client | 0.119 DEBUG Setting timeout of 324.403948ms client | 0.445 DEBUG [Client aaed18706965896bf8ad1506da] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 581, tv_nsec: 924594046 } } -> Closed(Application(0)) client | 0.445 INFO Closing timer expired client | 0.445 DEBUG Output::None client | 0.445 WARN Unhandled event StateChange(Closed(Application(0))) client | 0.445 DEBUG Timer fired while closed client | 0.445 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 12:00:29,000 Using the client's key log file. 2025-04-30 12:00:29,012 2025-04-30 12:00:29,013 Using the client's key log file. 2025-04-30 12:00:29,013 Using the client's key log file. 2025-04-30 12:00:29,023 2025-04-30 12:00:29,024 Using the client's key log file. 2025-04-30 12:00:29,599 Check of downloaded files succeeded. 2025-04-30 12:00:30,177 Check of downloaded files succeeded.