[2025-09-12T05:01:44.630123571Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T05:01:44.630406551Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:48469 with scid 5db059b6ee6934686cfacdf5cc396c0cb0b339ca
[2025-09-12T05:01:44.630445734Z TRACE quiche::tls] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca write message lvl=Initial len=266
[2025-09-12T05:01:44.630461333Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx pkt Initial version=1 dcid=b06492a820878d205d5b312629b6be2f scid=5db059b6ee6934686cfacdf5cc396c0cb0b339ca len=270 pn=0 
[2025-09-12T05:01:44.630464299Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm CRYPTO off=0 len=266
[2025-09-12T05:01:44.630477413Z TRACE quiche::recovery::congestion::recovery] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca timer=998.935739ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 424, tv_nsec: 628481534 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 628481534 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 424, tv_nsec: 628481534 }, next_time: Instant { tv_sec: 424, tv_nsec: 628481534 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T05:01:44.630527747Z TRACE quiche_apps::client] written 1200
[2025-09-12T05:01:44.668466478Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:48469
[2025-09-12T05:01:44.668482087Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx pkt Initial version=1 dcid=5db059b6ee6934686cfacdf5cc396c0cb0b339ca scid=2d55a8fdd7741ac0 token= len=116 pn=0 
[2025-09-12T05:01:44.668491695Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm ACK delay=54 blocks=[0..0] ecn_counts=None
[2025-09-12T05:01:44.668496594Z TRACE quiche::recovery::congestion::recovery] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca packet newly acked 0
[2025-09-12T05:01:44.668501102Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm CRYPTO off=0 len=90
[2025-09-12T05:01:44.668582354Z TRACE quiche::tls] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca set write secret lvl=Handshake
[2025-09-12T05:01:44.668590470Z TRACE quiche::tls] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca set read secret lvl=Handshake
[2025-09-12T05:01:44.668612731Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx pkt Handshake version=1 dcid=5db059b6ee6934686cfacdf5cc396c0cb0b339ca scid=2d55a8fdd7741ac0 len=1009 pn=0 
[2025-09-12T05:01:44.668618271Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm CRYPTO off=0 len=735
[2025-09-12T05:01:44.668811323Z TRACE quiche::tls] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca write message lvl=Handshake len=36
[2025-09-12T05:01:44.668815230Z TRACE quiche::tls] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca set write secret lvl=OneRTT
[2025-09-12T05:01:44.668821862Z TRACE quiche::tls] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca set read secret lvl=OneRTT
[2025-09-12T05:01:44.668836920Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(b06492a820878d205d5b312629b6be2f), max_idle_timeout: 30000, stateless_reset_token: Some(178536471789279323380274923877285533400), max_udp_payload_size: 1472, initial_max_data: 4611686018427387903, initial_max_stream_data_bidi_local: 1250000, initial_max_stream_data_bidi_remote: 1250000, initial_max_stream_data_uni: 1250000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 5, initial_source_connection_id: Some(2d55a8fdd7741ac0), retry_source_connection_id: None, max_datagram_frame_size: Some(65535), unknown_params: None }
[2025-09-12T05:01:44.668853952Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm PADDING len=253
[2025-09-12T05:01:44.668867648Z TRACE quiche_apps::client] 0.0.0.0:48469: processed 1200 bytes
[2025-09-12T05:01:44.668873158Z TRACE quiche_apps::client] got 234 bytes from 193.167.100.100:443 to 0.0.0.0:48469
[2025-09-12T05:01:44.668876945Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx pkt Short dcid=5db059b6ee6934686cfacdf5cc396c0cb0b339ca key_phase=false len=213 pn=0 
[2025-09-12T05:01:44.668883157Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[8c, 33, f5, 04, c7, 49, 0e, 0a] reset_token=[49, e7, c9, d2, 30, a9, 09, f0, 99, ed, fe, e9, 38, ac, 93, 98]
[2025-09-12T05:01:44.668889850Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[09, 5d, 93, 44, ab, 79, 6c, 92] reset_token=[51, 36, 7c, b5, 54, c3, 53, 43, e3, 28, 84, a2, ec, 95, b6, 09]
[2025-09-12T05:01:44.668894849Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[6c, 1a, bf, 91, 3c, 97, d4, 16] reset_token=[23, e4, 01, c6, db, 10, 13, 6e, a3, 26, 47, 6c, 72, 82, a5, e4]
[2025-09-12T05:01:44.668899588Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[f0, 90, f0, 01, 8d, 4e, 1d, 80] reset_token=[68, b4, b4, 87, 68, 37, 81, 1a, a2, 01, 09, 6d, 20, 35, e2, 35]
[2025-09-12T05:01:44.668904898Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[6c, c8, 16, 77, 51, 21, 9e, ba] reset_token=[73, 05, 75, 72, 76, 8b, cf, 20, 30, 45, 4a, 93, f1, b4, 56, 91]
[2025-09-12T05:01:44.668909777Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[0f, 26, 7c, 64, a9, b7, 6e, f8] reset_token=[7b, 5d, e6, 05, 98, 0d, 63, e3, 65, 1f, 64, 41, 91, b6, 5c, b6]
[2025-09-12T05:01:44.668914596Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[ec, 96, fc, 64, 17, 11, f9, 8d] reset_token=[84, 0d, e5, 53, 90, 45, 3b, 1e, e2, a6, 04, e5, 43, 8a, b9, a0]
[2025-09-12T05:01:44.668922992Z TRACE quiche_apps::client] 0.0.0.0:48469: processed 234 bytes
[2025-09-12T05:01:44.668926819Z TRACE quiche_apps::client] 0.0.0.0:48469: recv() would block
[2025-09-12T05:01:44.668929243Z TRACE quiche_apps::client] done reading
[2025-09-12T05:01:44.668966172Z DEBUG quiche_apps::common] sending HTTP request "GET /irhzodxfte\r\n"
[2025-09-12T05:01:44.669018390Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx pkt Initial version=1 dcid=2d55a8fdd7741ac0 scid=5db059b6ee6934686cfacdf5cc396c0cb0b339ca len=6 pn=1 
[2025-09-12T05:01:44.669022137Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm ACK delay=67 blocks=[0..0] ecn_counts=None
[2025-09-12T05:01:44.669031144Z TRACE quiche::recovery::congestion::recovery] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca timer=113.645781ms latest_rtt=38.065908ms srtt=38.065908ms min_rtt=38.065908ms rttvar=19.032954ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 424, tv_nsec: 666903438 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 666903438 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.98 Kbps, is_app_limited: true, interval: 38.065908ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 628481534 }), send_elapsed: 0ns, ack_elapsed: 38.065908ms, rtt: 38.065908ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 443310, last_update: Instant { tv_sec: 424, tv_nsec: 666903408 }, next_time: Instant { tv_sec: 424, tv_nsec: 666903438 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T05:01:44.669054567Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx pkt Handshake version=1 dcid=2d55a8fdd7741ac0 scid=5db059b6ee6934686cfacdf5cc396c0cb0b339ca len=45 pn=2 
[2025-09-12T05:01:44.669057844Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm ACK delay=55 blocks=[0..0] ecn_counts=None
[2025-09-12T05:01:44.669060438Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm CRYPTO off=0 len=36
[2025-09-12T05:01:44.669066229Z TRACE quiche::recovery::congestion::recovery] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca timer=114.14237ms latest_rtt=38.065908ms srtt=38.065908ms min_rtt=38.065908ms rttvar=19.032954ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 424, tv_nsec: 666903438 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 666903438 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.98 Kbps, is_app_limited: true, interval: 38.065908ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 628481534 }), send_elapsed: 0ns, ack_elapsed: 38.065908ms, rtt: 38.065908ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 443310, last_update: Instant { tv_sec: 424, tv_nsec: 667078956 }, next_time: Instant { tv_sec: 424, tv_nsec: 666903438 }, max_datagram_size: 1350, last_packet_size: None, iv: 223.32µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T05:01:44.669078322Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca dropped epoch 0 state
[2025-09-12T05:01:44.669090725Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx pkt Short dcid=2d55a8fdd7741ac0 key_phase=false len=1164 pn=3 
[2025-09-12T05:01:44.669093470Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm ACK delay=25 blocks=[0..0] ecn_counts=None
[2025-09-12T05:01:44.669095975Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[8f, 26, 61, 43, 0e, 66, ca, 84, 12, 31, 24, 13, 18, f3, c9, 19, 66, a2, af, a1] reset_token=[2b, bc, 4f, 5f, 7b, a7, 86, bb, 94, 1f, 39, 26, 99, d0, 66, ad]
[2025-09-12T05:01:44.669102227Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[cc, 60, 2c, 09, 67, 83, f9, 29, 77, 44, 64, 25, 9b, 57, 76, 71, ac, 4d, 04, 7f] reset_token=[b5, 7b, e7, 98, 24, b7, 3a, d4, bc, f2, 9b, 56, 6e, 1c, be, b5]
[2025-09-12T05:01:44.669107917Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[dd, 08, 5e, 0b, 66, f4, 38, 0a, ab, 24, 58, 2f, 6c, a4, 16, ea, 52, f3, a3, 88] reset_token=[bc, 8b, e8, 69, 1f, 5f, e1, 0a, 2b, 92, 0a, 49, 97, d6, 5a, d0]
[2025-09-12T05:01:44.669113698Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[28, 5d, 17, 4d, 06, 97, 7f, b5, 07, 1c, f8, 0b, f2, 3f, 23, 72, 11, fe, 2c, 9e] reset_token=[37, e6, 62, 81, 51, d7, 9e, 46, a5, 2e, 5a, 7c, 8b, 77, 0c, 22]
[2025-09-12T05:01:44.669122975Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm STREAM id=0 off=0 len=17 fin=true
[2025-09-12T05:01:44.669125611Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm PADDING len=977
[2025-09-12T05:01:44.669133325Z TRACE quiche::recovery::congestion::recovery] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca timer=114.075304ms latest_rtt=38.065908ms srtt=38.065908ms min_rtt=38.065908ms rttvar=19.032954ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 424, tv_nsec: 666903438 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 666903438 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.98 Kbps, is_app_limited: true, interval: 38.065908ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 628481534 }), send_elapsed: 0ns, ack_elapsed: 38.065908ms, rtt: 38.065908ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1190, rate: 443310, last_update: Instant { tv_sec: 424, tv_nsec: 667078956 }, next_time: Instant { tv_sec: 424, tv_nsec: 667302276 }, max_datagram_size: 1350, last_packet_size: Some(1190), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T05:01:44.669168400Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:48469 to 193.167.100.100:443
[2025-09-12T05:01:44.669173420Z TRACE quiche_apps::client] 0.0.0.0:48469 -> 193.167.100.100:443: done writing
[2025-09-12T05:01:44.701147372Z TRACE quiche_apps::client] got 406 bytes from 193.167.100.100:443 to 0.0.0.0:48469
[2025-09-12T05:01:44.701162500Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx pkt Short dcid=8f2661430e66ca841231241318f3c91966a2afa1 key_phase=false len=385 pn=1 
[2025-09-12T05:01:44.701170214Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca path ID 0 now see SCID with seq num 1
[2025-09-12T05:01:44.701174522Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm HANDSHAKE_DONE
[2025-09-12T05:01:44.701179121Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca dropped epoch 1 state
[2025-09-12T05:01:44.701182808Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm ACK delay=14 blocks=[3..3] ecn_counts=None
[2025-09-12T05:01:44.701187146Z TRACE quiche::recovery::congestion::recovery] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca packet newly acked 3
[2025-09-12T05:01:44.701192025Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm CRYPTO off=0 len=356
[2025-09-12T05:01:44.701222562Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-09-12T05:01:44.701236819Z TRACE quiche_apps::client] 0.0.0.0:48469: processed 406 bytes
[2025-09-12T05:01:44.701241528Z TRACE quiche_apps::client] 0.0.0.0:48469: recv() would block
[2025-09-12T05:01:44.701244584Z TRACE quiche_apps::client] done reading
[2025-09-12T05:01:44.701247659Z INFO  quiche_apps::client] Retiring source CID 5db059b6ee6934686cfacdf5cc396c0cb0b339ca
[2025-09-12T05:01:44.701261095Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx pkt Short dcid=2d55a8fdd7741ac0 key_phase=false len=45 pn=4 
[2025-09-12T05:01:44.701264871Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm ACK delay=12 blocks=[1..1] ecn_counts=None
[2025-09-12T05:01:44.701268418Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=1 conn_id=[50, d1, bc, 8d, 4c, 36, 65, 92, 09, 87, 0c, 77, ce, 26, 70, 5e, 1d, 11, a6, 6f] reset_token=[45, e5, a8, 98, f3, 1d, d5, 65, df, 8e, fe, ee, 6b, 8d, 06, 47]
[2025-09-12T05:01:44.701281462Z TRACE quiche::recovery::congestion::recovery] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca timer=125.510263ms latest_rtt=31.926321ms srtt=37.298459ms min_rtt=31.926321ms rttvar=15.809611ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1523, delivered_time: Instant { tv_sec: 424, tv_nsec: 699323154 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 699323154 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.50 Kbps, is_app_limited: true, interval: 32.325159ms, delivered: 1190, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 666903438 }), send_elapsed: 398.838µs, ack_elapsed: 32.325159ms, rtt: 31.926321ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 71, rate: 452431, last_update: Instant { tv_sec: 424, tv_nsec: 699323154 }, next_time: Instant { tv_sec: 424, tv_nsec: 699323154 }, max_datagram_size: 1350, last_packet_size: Some(71), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T05:01:44.701353217Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:48469 to 193.167.100.100:443
[2025-09-12T05:01:44.701359809Z TRACE quiche_apps::client] 0.0.0.0:48469 -> 193.167.100.100:443: done writing
[2025-09-12T05:01:44.701951055Z TRACE quiche_apps::client] got 1066 bytes from 193.167.100.100:443 to 0.0.0.0:48469
[2025-09-12T05:01:44.701963669Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx pkt Short dcid=8f2661430e66ca841231241318f3c91966a2afa1 key_phase=false len=1045 pn=2 
[2025-09-12T05:01:44.701971223Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm STREAM id=0 off=0 len=1024 fin=false
[2025-09-12T05:01:44.701979819Z TRACE quiche_apps::client] 0.0.0.0:48469: processed 1066 bytes
[2025-09-12T05:01:44.701984448Z TRACE quiche_apps::client] got 43 bytes from 193.167.100.100:443 to 0.0.0.0:48469
[2025-09-12T05:01:44.701987563Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx pkt Short dcid=8f2661430e66ca841231241318f3c91966a2afa1 key_phase=false len=22 pn=3 
[2025-09-12T05:01:44.701991421Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca rx frm STREAM id=0 off=1024 len=0 fin=true
[2025-09-12T05:01:44.701996300Z TRACE quiche_apps::client] 0.0.0.0:48469: processed 43 bytes
[2025-09-12T05:01:44.701999696Z TRACE quiche_apps::client] 0.0.0.0:48469: recv() would block
[2025-09-12T05:01:44.702002110Z TRACE quiche_apps::client] done reading
[2025-09-12T05:01:44.702007761Z TRACE quiche_apps::common] received 1024 bytes
[2025-09-12T05:01:44.702010165Z TRACE quiche_apps::common] stream 0 has 1024 bytes (fin? true)
[2025-09-12T05:01:44.702012510Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-12T05:01:44.702014784Z INFO  quiche_apps::common] 1/1 response(s) received in 71.483581ms, closing...
[2025-09-12T05:01:44.702022208Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx pkt Short dcid=2d55a8fdd7741ac0 key_phase=false len=10 pn=5 
[2025-09-12T05:01:44.702024933Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T05:01:44.702031826Z TRACE quiche::recovery::congestion::recovery] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca timer=125.523708ms latest_rtt=31.926321ms srtt=37.298459ms min_rtt=31.926321ms rttvar=15.809611ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=107 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1523, delivered_time: Instant { tv_sec: 424, tv_nsec: 699323154 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 699323154 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.50 Kbps, is_app_limited: true, interval: 32.325159ms, delivered: 1190, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 666903438 }), send_elapsed: 398.838µs, ack_elapsed: 32.325159ms, rtt: 31.926321ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 452431, last_update: Instant { tv_sec: 424, tv_nsec: 700086852 }, next_time: Instant { tv_sec: 424, tv_nsec: 699323154 }, max_datagram_size: 1350, last_packet_size: None, iv: 236.5µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T05:01:44.702074636Z TRACE quiche_apps::client] written 36 bytes from 0.0.0.0:48469 to 193.167.100.100:443
[2025-09-12T05:01:44.702078754Z TRACE quiche_apps::client] 0.0.0.0:48469 -> 193.167.100.100:443: done writing
[2025-09-12T05:01:44.732502958Z TRACE quiche_apps::client] got 47 bytes from 193.167.100.100:443 to 0.0.0.0:48469
[2025-09-12T05:01:44.732516673Z TRACE quiche_apps::client] 0.0.0.0:48469: processed 47 bytes
[2025-09-12T05:01:44.732521683Z TRACE quiche_apps::client] 0.0.0.0:48469: recv() would block
[2025-09-12T05:01:44.732525730Z TRACE quiche_apps::client] done reading
[2025-09-12T05:01:44.732536170Z TRACE quiche_apps::client] 0.0.0.0:48469 -> 193.167.100.100:443: done writing
[2025-09-12T05:01:45.004861402Z TRACE quiche_apps::client] timed out
[2025-09-12T05:01:45.004875729Z TRACE quiche] 5db059b6ee6934686cfacdf5cc396c0cb0b339ca draining timeout expired
[2025-09-12T05:01:45.004903460Z TRACE quiche_apps::client] done reading
[2025-09-12T05:01:45.004908871Z INFO  quiche_apps::client] connection closed, recv=6 sent=6 lost=0 retrans=0 sent_bytes=1790 recv_bytes=2949 lost_bytes=0 [local_addr=0.0.0.0:48469 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=6 sent=6 lost=0 retrans=0 rtt=37.298459ms min_rtt=Some(31.926321ms) rttvar=15.809611ms cwnd=13500 sent_bytes=1790 recv_bytes=2949 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=36813]