[2025-12-13T09:16:44.534068361Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-12-13T09:16:44.534346803Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:38192 with scid fa0c509cd050167e4b82298a175e9e96b9b19f06
[2025-12-13T09:16:44.534388410Z TRACE quiche::tls] fa0c509cd050167e4b82298a175e9e96b9b19f06 write message lvl=Initial len=266
[2025-12-13T09:16:44.534406084Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Initial version=1 dcid=7baf7d53802b693553e4d959a4ead72c scid=fa0c509cd050167e4b82298a175e9e96b9b19f06 len=270 pn=0 
[2025-12-13T09:16:44.534410111Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm CRYPTO off=0 len=266
[2025-12-13T09:16:44.534424669Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=998.929628ms 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: 159, tv_nsec: 634436216 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 634436216 }, 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: 159, tv_nsec: 634436216 }, next_time: Instant { tv_sec: 159, tv_nsec: 634436216 }, 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-12-13T09:16:44.534477808Z TRACE quiche_apps::client] written 1200
[2025-12-13T09:16:44.572592469Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:38192
[2025-12-13T09:16:44.572616264Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx pkt Initial version=1 dcid=fa0c509cd050167e4b82298a175e9e96b9b19f06 scid=400000c7dbe2b9ab token= len=1194 pn=7918169 
[2025-12-13T09:16:44.572629499Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm CRYPTO off=0 len=90
[2025-12-13T09:16:44.572742330Z TRACE quiche::tls] fa0c509cd050167e4b82298a175e9e96b9b19f06 set write secret lvl=Handshake
[2025-12-13T09:16:44.572753841Z TRACE quiche::tls] fa0c509cd050167e4b82298a175e9e96b9b19f06 set read secret lvl=Handshake
[2025-12-13T09:16:44.572764992Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm ACK delay=97 blocks=[0..0] ecn_counts=None
[2025-12-13T09:16:44.572771545Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 packet newly acked 0
[2025-12-13T09:16:44.572782685Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm PADDING len=1075
[2025-12-13T09:16:44.572806420Z TRACE quiche_apps::client] 0.0.0.0:38192: processed 1232 bytes
[2025-12-13T09:16:44.572813333Z TRACE quiche_apps::client] 0.0.0.0:38192: recv() would block
[2025-12-13T09:16:44.572817190Z TRACE quiche_apps::client] done reading
[2025-12-13T09:16:44.572835113Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Initial version=1 dcid=400000c7dbe2b9ab scid=fa0c509cd050167e4b82298a175e9e96b9b19f06 len=8 pn=1 
[2025-12-13T09:16:44.572839742Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm ACK delay=27 blocks=[7918169..7918169] ecn_counts=None
[2025-12-13T09:16:44.572849600Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=114.53517ms latest_rtt=38.257629ms srtt=38.257629ms min_rtt=38.257629ms rttvar=19.128814ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 159, tv_nsec: 672907845 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 672907845 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.63 Kbps, is_app_limited: true, interval: 38.257629ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 634436216 }), send_elapsed: 0ns, ack_elapsed: 38.257629ms, rtt: 38.257629ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 441088, last_update: Instant { tv_sec: 159, tv_nsec: 672907845 }, next_time: Instant { tv_sec: 159, tv_nsec: 672907845 }, 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-12-13T09:16:44.572910986Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:38192 to 193.167.100.100:443
[2025-12-13T09:16:44.572948125Z TRACE quiche_apps::client] 0.0.0.0:38192 -> 193.167.100.100:443: done writing
[2025-12-13T09:16:44.573226536Z TRACE quiche_apps::client] got 774 bytes from 193.167.100.100:443 to 0.0.0.0:38192
[2025-12-13T09:16:44.573239030Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx pkt Handshake version=1 dcid=fa0c509cd050167e4b82298a175e9e96b9b19f06 scid=400000c7dbe2b9ab len=737 pn=7918169 
[2025-12-13T09:16:44.573248247Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm CRYPTO off=0 len=714
[2025-12-13T09:16:44.573468359Z TRACE quiche::tls] fa0c509cd050167e4b82298a175e9e96b9b19f06 write message lvl=Handshake len=36
[2025-12-13T09:16:44.573474280Z TRACE quiche::tls] fa0c509cd050167e4b82298a175e9e96b9b19f06 set write secret lvl=OneRTT
[2025-12-13T09:16:44.573482115Z TRACE quiche::tls] fa0c509cd050167e4b82298a175e9e96b9b19f06 set read secret lvl=OneRTT
[2025-12-13T09:16:44.573498435Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(7baf7d53802b693553e4d959a4ead72c), max_idle_timeout: 60000, stateless_reset_token: Some(320116753475194796874344744727888835210), max_udp_payload_size: 1500, initial_max_data: 107374182, initial_max_stream_data_bidi_local: 107374182, initial_max_stream_data_bidi_remote: 107374182, initial_max_stream_data_uni: 107374182, 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: 2, initial_source_connection_id: Some(400000c7dbe2b9ab), retry_source_connection_id: None, max_datagram_frame_size: Some(65535), unknown_params: None }
[2025-12-13T09:16:44.573523412Z TRACE quiche_apps::client] 0.0.0.0:38192: processed 774 bytes
[2025-12-13T09:16:44.573529584Z TRACE quiche_apps::client] got 112 bytes from 193.167.100.100:443 to 0.0.0.0:38192
[2025-12-13T09:16:44.573534263Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx pkt Short dcid=fa0c509cd050167e4b82298a175e9e96b9b19f06 key_phase=false len=91 pn=7918169 
[2025-12-13T09:16:44.573540895Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm STREAM id=3 off=0 len=41 fin=false
[2025-12-13T09:16:44.573546175Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-12-13T09:16:44.573550333Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-12-13T09:16:44.573554110Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm PADDING len=20
[2025-12-13T09:16:44.573560031Z TRACE quiche_apps::client] 0.0.0.0:38192: processed 112 bytes
[2025-12-13T09:16:44.573564169Z TRACE quiche_apps::client] 0.0.0.0:38192: recv() would block
[2025-12-13T09:16:44.573567254Z TRACE quiche_apps::client] done reading
[2025-12-13T09:16:44.573581241Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm SETTINGS stream=2 len=18
[2025-12-13T09:16:44.573599605Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 open GREASE stream 14
[2025-12-13T09:16:44.573609163Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm GREASE stream=0 len=0
[2025-12-13T09:16:44.573615585Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm GREASE stream=0 len=18
[2025-12-13T09:16:44.573623149Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm HEADERS stream=0 len=36 fin=true
[2025-12-13T09:16:44.573629541Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /endless-scalding-usb", "user-agent: quiche"]
[2025-12-13T09:16:44.573672972Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 stream id 3 is readable
[2025-12-13T09:16:44.573679224Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 3
[2025-12-13T09:16:44.573683672Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 open peer's control stream 3
[2025-12-13T09:16:44.573687590Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 3
[2025-12-13T09:16:44.573691377Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 3
[2025-12-13T09:16:44.573695735Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 38 bytes on stream 3
[2025-12-13T09:16:44.573700063Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm SETTINGS max_field_section=Some(131072), qpack_max_table=Some(4096), qpack_blocked=Some(100) raw=Some([(1, 4096), (6, 131072), (7, 100), (8, 1), (16765559, 1), (630, 1), (51, 1), (727725890, 1), (126, 4207849484)]), additional_settings=Some([(16765559, 1), (727725890, 1), (126, 4207849484)]) stream=3 payload_len=38
[2025-12-13T09:16:44.573726092Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 stream id 7 is readable
[2025-12-13T09:16:44.573730871Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 7
[2025-12-13T09:16:44.573735109Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 stream id 11 is readable
[2025-12-13T09:16:44.573739387Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 11
[2025-12-13T09:16:44.573753052Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Handshake version=1 dcid=400000c7dbe2b9ab scid=fa0c509cd050167e4b82298a175e9e96b9b19f06 len=49 pn=2 
[2025-12-13T09:16:44.573756970Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm ACK delay=64 blocks=[7918169..7918169] ecn_counts=None
[2025-12-13T09:16:44.573760917Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm CRYPTO off=0 len=36
[2025-12-13T09:16:44.573770184Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=114.747167ms latest_rtt=38.257629ms srtt=38.257629ms min_rtt=38.257629ms rttvar=19.128814ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=103 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.63 Kbps, is_app_limited: true, interval: 38.257629ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 634436216 }), send_elapsed: 0ns, ack_elapsed: 38.257629ms, rtt: 38.257629ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 103, rate: 441088, last_update: Instant { tv_sec: 159, tv_nsec: 673577970 }, next_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, max_datagram_size: 1350, last_packet_size: Some(103), 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-12-13T09:16:44.573792346Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 dropped epoch 0 state
[2025-12-13T09:16:44.573801213Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Short dcid=400000c7dbe2b9ab key_phase=false len=72 pn=3 
[2025-12-13T09:16:44.573804599Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm ACK delay=32 blocks=[7918169..7918169] ecn_counts=None
[2025-12-13T09:16:44.573808005Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e4, 9c, 97, 55, 29, 40, 69, d7, 00, 5a, 87, 3f, 17, 6d, ca, d2, 86, 73, 56, 24] reset_token=[8b, 5f, f7, 03, 67, 4f, 91, 3c, 09, ad, 4b, d4, 43, 64, c1, 84]
[2025-12-13T09:16:44.573816291Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-12-13T09:16:44.573823624Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=114.693887ms latest_rtt=38.257629ms srtt=38.257629ms min_rtt=38.257629ms rttvar=19.128814ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=201 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.63 Kbps, is_app_limited: true, interval: 38.257629ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 634436216 }), send_elapsed: 0ns, ack_elapsed: 38.257629ms, rtt: 38.257629ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 441088, last_update: Instant { tv_sec: 159, tv_nsec: 673826315 }, next_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, max_datagram_size: 1350, last_packet_size: None, iv: 455.691µ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-12-13T09:16:44.573863249Z TRACE quiche_apps::client] written 201 bytes from 0.0.0.0:38192 to 193.167.100.100:443
[2025-12-13T09:16:44.573872065Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Short dcid=400000c7dbe2b9ab key_phase=false len=6 pn=4 
[2025-12-13T09:16:44.573878197Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-12-13T09:16:44.573888897Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=114.628214ms latest_rtt=38.257629ms srtt=38.257629ms min_rtt=38.257629ms rttvar=19.128814ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=233 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.63 Kbps, is_app_limited: true, interval: 38.257629ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 634436216 }), send_elapsed: 0ns, ack_elapsed: 38.257629ms, rtt: 38.257629ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 441088, last_update: Instant { tv_sec: 159, tv_nsec: 673826315 }, next_time: Instant { tv_sec: 159, tv_nsec: 674404465 }, max_datagram_size: 1350, last_packet_size: Some(32), 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-12-13T09:16:44.573954730Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:38192 to 193.167.100.100:443
[2025-12-13T09:16:44.573973415Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Short dcid=400000c7dbe2b9ab key_phase=false len=6 pn=5 
[2025-12-13T09:16:44.573985628Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-12-13T09:16:44.573997269Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=114.51943ms latest_rtt=38.257629ms srtt=38.257629ms min_rtt=38.257629ms rttvar=19.128814ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=265 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.63 Kbps, is_app_limited: true, interval: 38.257629ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 634436216 }), send_elapsed: 0ns, ack_elapsed: 38.257629ms, rtt: 38.257629ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 64, rate: 441088, last_update: Instant { tv_sec: 159, tv_nsec: 673826315 }, next_time: Instant { tv_sec: 159, tv_nsec: 674404465 }, max_datagram_size: 1350, last_packet_size: Some(32), 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-12-13T09:16:44.574043937Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:38192 to 193.167.100.100:443
[2025-12-13T09:16:44.574054106Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Short dcid=400000c7dbe2b9ab key_phase=false len=79 pn=6 
[2025-12-13T09:16:44.574059697Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm STREAM id=0 off=0 len=74 fin=true
[2025-12-13T09:16:44.574070577Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=114.446143ms latest_rtt=38.257629ms srtt=38.257629ms min_rtt=38.257629ms rttvar=19.128814ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=370 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.63 Kbps, is_app_limited: true, interval: 38.257629ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 634436216 }), send_elapsed: 0ns, ack_elapsed: 38.257629ms, rtt: 38.257629ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 441088, last_update: Instant { tv_sec: 159, tv_nsec: 674129533 }, next_time: Instant { tv_sec: 159, tv_nsec: 674404465 }, max_datagram_size: 1350, last_packet_size: None, iv: 383.143µ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-12-13T09:16:44.574118376Z TRACE quiche_apps::client] written 105 bytes from 0.0.0.0:38192 to 193.167.100.100:443
[2025-12-13T09:16:44.574130189Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Short dcid=400000c7dbe2b9ab key_phase=false len=31 pn=7 
[2025-12-13T09:16:44.574135799Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm STREAM id=14 off=0 len=26 fin=true
[2025-12-13T09:16:44.574148172Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=114.368578ms latest_rtt=38.257629ms srtt=38.257629ms min_rtt=38.257629ms rttvar=19.128814ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=427 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 673578000 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.63 Kbps, is_app_limited: true, interval: 38.257629ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 634436216 }), send_elapsed: 0ns, ack_elapsed: 38.257629ms, rtt: 38.257629ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 57, rate: 441088, last_update: Instant { tv_sec: 159, tv_nsec: 674129533 }, next_time: Instant { tv_sec: 159, tv_nsec: 674787608 }, max_datagram_size: 1350, last_packet_size: Some(57), 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-12-13T09:16:44.574204007Z TRACE quiche_apps::client] written 57 bytes from 0.0.0.0:38192 to 193.167.100.100:443
[2025-12-13T09:16:44.574211240Z TRACE quiche_apps::client] 0.0.0.0:38192 -> 193.167.100.100:443: done writing
[2025-12-13T09:16:44.604629878Z TRACE quiche_apps::client] got 58 bytes from 193.167.100.100:443 to 0.0.0.0:38192
[2025-12-13T09:16:44.604644305Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx pkt Handshake version=1 dcid=fa0c509cd050167e4b82298a175e9e96b9b19f06 scid=400000c7dbe2b9ab len=22 pn=7918170 
[2025-12-13T09:16:44.604652701Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm ACK delay=26 blocks=[2..2] ecn_counts=None
[2025-12-13T09:16:44.604657279Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 packet newly acked 2
[2025-12-13T09:16:44.604671376Z TRACE quiche_apps::client] 0.0.0.0:38192: processed 58 bytes
[2025-12-13T09:16:44.604676054Z TRACE quiche_apps::client] 0.0.0.0:38192: recv() would block
[2025-12-13T09:16:44.604679020Z TRACE quiche_apps::client] done reading
[2025-12-13T09:16:44.604685041Z TRACE quiche_apps::client] 0.0.0.0:38192 -> 193.167.100.100:443: done writing
[2025-12-13T09:16:44.604983530Z TRACE quiche_apps::client] got 432 bytes from 193.167.100.100:443 to 0.0.0.0:38192
[2025-12-13T09:16:44.604991996Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx pkt Short dcid=fa0c509cd050167e4b82298a175e9e96b9b19f06 key_phase=false len=411 pn=7918170 
[2025-12-13T09:16:44.604998758Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm CRYPTO off=0 len=242
[2025-12-13T09:16:44.605016642Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm ACK delay=32 blocks=[3..3] ecn_counts=None
[2025-12-13T09:16:44.605023254Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 packet newly acked 3
[2025-12-13T09:16:44.605030338Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm HANDSHAKE_DONE
[2025-12-13T09:16:44.605036329Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 dropped epoch 1 state
[2025-12-13T09:16:44.605043823Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[40, 00, 00, c8, 8e, 6a, c0, 3e] reset_token=[52, f4, 09, 09, 3e, ba, cd, 50, 8f, 63, e9, 41, ef, 6e, 08, 12]
[2025-12-13T09:16:44.605056306Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[40, 00, 00, d6, e5, ad, 4a, 57] reset_token=[9b, b5, 57, 88, 34, 73, 96, 75, 95, 72, 03, ec, a3, 82, 5d, 4a]
[2025-12-13T09:16:44.605066225Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[40, 00, 00, df, 38, 18, 26, 9d] reset_token=[0b, 58, de, 5a, 85, 89, c1, 0c, 76, 93, a4, 8a, 20, cb, 38, aa]
[2025-12-13T09:16:44.605076314Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[40, 00, 00, de, 76, 18, 14, e2] reset_token=[3e, bf, f5, 91, 35, d0, a6, 7e, 1d, 55, 82, a0, 71, cb, b6, 32]
[2025-12-13T09:16:44.605085200Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm PADDING len=30
[2025-12-13T09:16:44.605110267Z TRACE quiche_apps::client] 0.0.0.0:38192: processed 432 bytes
[2025-12-13T09:16:44.605119394Z TRACE quiche_apps::client] got 112 bytes from 193.167.100.100:443 to 0.0.0.0:38192
[2025-12-13T09:16:44.605126417Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx pkt Short dcid=fa0c509cd050167e4b82298a175e9e96b9b19f06 key_phase=false len=91 pn=7918171 
[2025-12-13T09:16:44.605136266Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm ACK delay=54 blocks=[3..6] ecn_counts=None
[2025-12-13T09:16:44.605142979Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 packet newly acked 4
[2025-12-13T09:16:44.605148198Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 packet newly acked 5
[2025-12-13T09:16:44.605152957Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 packet newly acked 6
[2025-12-13T09:16:44.605159389Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm STREAM id=0 off=0 len=62 fin=false
[2025-12-13T09:16:44.605165040Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm PADDING len=4
[2025-12-13T09:16:44.605180950Z TRACE quiche_apps::client] 0.0.0.0:38192: processed 112 bytes
[2025-12-13T09:16:44.605188233Z TRACE quiche_apps::client] 0.0.0.0:38192: recv() would block
[2025-12-13T09:16:44.605192842Z TRACE quiche_apps::client] done reading
[2025-12-13T09:16:44.605196348Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 stream id 0 is readable
[2025-12-13T09:16:44.605203542Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 0
[2025-12-13T09:16:44.605209824Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 0
[2025-12-13T09:16:44.605215544Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 0
[2025-12-13T09:16:44.605220714Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm UNKNOWN raw_type=157 stream=0 payload_len=0
[2025-12-13T09:16:44.605229170Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 0
[2025-12-13T09:16:44.605234921Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 0
[2025-12-13T09:16:44.605242004Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 57 bytes on stream 0
[2025-12-13T09:16:44.605247755Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm HEADERS stream=0 payload_len=57
[2025-12-13T09:16:44.605253446Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-12-13T09:16:44.605258335Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-12-13T09:16:44.605266129Z TRACE quiche::h3::qpack::decoder] Literal name_idx=83 static=true value=[104, 51, 61, 34, 58, 52, 52, 51, 34, 59, 32, 109, 97, 61, 51, 54, 48, 48]
[2025-12-13T09:16:44.605272962Z TRACE quiche::h3::qpack::decoder] Literal Without Name Reference name=[112, 114, 105, 111, 114, 105, 116, 121] value=[117, 61, 51, 44, 105]
[2025-12-13T09:16:44.605278673Z TRACE quiche::h3::qpack::decoder] Literal name_idx=6 static=true value=[83, 97, 116, 44, 32, 49, 51, 32, 68, 101, 99, 32, 50, 48, 50, 53, 32, 48, 57, 58, 49, 54, 58, 52, 52, 32, 71, 77, 84]
[2025-12-13T09:16:44.605287129Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("alt-svc", "h3=\":443\"; ma=3600"), ("priority", "u=3,i"), ("date", "Sat, 13 Dec 2025 09:16:44 GMT")] on stream id 0
[2025-12-13T09:16:44.605302808Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Short dcid=400000c7dbe2b9ab key_phase=false len=8 pn=8 
[2025-12-13T09:16:44.605308498Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm ACK delay=21 blocks=[7918170..7918171] ecn_counts=None
[2025-12-13T09:16:44.605319840Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=76.358873ms latest_rtt=30.801828ms srtt=35.8772ms min_rtt=30.801828ms rttvar=11.669752ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=57 app_limited=true congestion_recovery_start_time=None Rate { delivered: 703, delivered_time: Instant { tv_sec: 159, tv_nsec: 705206293 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 674404465 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 6, rate_sample: RateSample { bandwidth: 93.58 Kbps, is_app_limited: true, interval: 31.628293ms, delivered: 370, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 673578000 }), send_elapsed: 826.465µs, ack_elapsed: 31.628293ms, rtt: 30.801828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 470354, last_update: Instant { tv_sec: 159, tv_nsec: 705377553 }, next_time: Instant { tv_sec: 159, tv_nsec: 705377553 }, 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-12-13T09:16:44.605378650Z TRACE quiche_apps::client] written 34 bytes from 0.0.0.0:38192 to 193.167.100.100:443
[2025-12-13T09:16:44.605388608Z TRACE quiche_apps::client] 0.0.0.0:38192 -> 193.167.100.100:443: done writing
[2025-12-13T09:16:44.605986358Z TRACE quiche_apps::client] got 1104 bytes from 193.167.100.100:443 to 0.0.0.0:38192
[2025-12-13T09:16:44.605999332Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx pkt Short dcid=fa0c509cd050167e4b82298a175e9e96b9b19f06 key_phase=false len=1083 pn=7918172 
[2025-12-13T09:16:44.606006816Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm ACK delay=65 blocks=[3..7] ecn_counts=None
[2025-12-13T09:16:44.606011134Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 packet newly acked 7
[2025-12-13T09:16:44.606015653Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm STREAM id=0 off=62 len=1027 fin=true
[2025-12-13T09:16:44.606019870Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm PADDING len=28
[2025-12-13T09:16:44.606031432Z TRACE quiche_apps::client] 0.0.0.0:38192: processed 1104 bytes
[2025-12-13T09:16:44.606035820Z TRACE quiche_apps::client] 0.0.0.0:38192: recv() would block
[2025-12-13T09:16:44.606038706Z TRACE quiche_apps::client] done reading
[2025-12-13T09:16:44.606041742Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 stream id 0 is readable
[2025-12-13T09:16:44.606057291Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 0
[2025-12-13T09:16:44.606061448Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 0
[2025-12-13T09:16:44.606065015Z TRACE quiche::h3::stream] fa0c509cd050167e4b82298a175e9e96b9b19f06 read 1 bytes on stream 0
[2025-12-13T09:16:44.606067891Z TRACE quiche::h3] fa0c509cd050167e4b82298a175e9e96b9b19f06 rx frm DATA stream=0 wire_payload_len=1024
[2025-12-13T09:16:44.606074804Z DEBUG quiche_apps::common] got 1024 bytes of response data on stream 0
[2025-12-13T09:16:44.606078270Z DEBUG quiche_apps::common] 1/1 responses received
[2025-12-13T09:16:44.606081085Z INFO  quiche_apps::common] 1/1 response(s) received in 71.599099ms, closing...
[2025-12-13T09:16:44.606089681Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx pkt Short dcid=400000c7dbe2b9ab key_phase=false len=11 pn=9 
[2025-12-13T09:16:44.606093068Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-12-13T09:16:44.606101393Z TRACE quiche::recovery::congestion::recovery] fa0c509cd050167e4b82298a175e9e96b9b19f06 timer=99.88288ms latest_rtt=31.291399ms srtt=35.303974ms min_rtt=30.801828ms rttvar=9.898764ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 760, delivered_time: Instant { tv_sec: 159, tv_nsec: 705377553 }, first_sent_time: Instant { tv_sec: 159, tv_nsec: 705377553 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 7, rate_sample: RateSample { bandwidth: 105.10 Kbps, is_app_limited: true, interval: 32.501007ms, delivered: 427, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 159, tv_nsec: 673578000 }), send_elapsed: 1.209608ms, ack_elapsed: 32.501007ms, rtt: 31.291399ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 477991, last_update: Instant { tv_sec: 159, tv_nsec: 706166962 }, next_time: Instant { tv_sec: 159, tv_nsec: 705377553 }, max_datagram_size: 1350, last_packet_size: None, iv: 77.407µ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-12-13T09:16:44.606141007Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:38192 to 193.167.100.100:443
[2025-12-13T09:16:44.606145746Z TRACE quiche_apps::client] 0.0.0.0:38192 -> 193.167.100.100:443: done writing
[2025-12-13T09:16:44.636555989Z TRACE quiche_apps::client] got 53 bytes from 193.167.100.100:443 to 0.0.0.0:38192
[2025-12-13T09:16:44.636572100Z TRACE quiche_apps::client] 0.0.0.0:38192: processed 53 bytes
[2025-12-13T09:16:44.636577720Z TRACE quiche_apps::client] 0.0.0.0:38192: recv() would block
[2025-12-13T09:16:44.636581928Z TRACE quiche_apps::client] done reading
[2025-12-13T09:16:44.636587969Z TRACE quiche_apps::client] 0.0.0.0:38192 -> 193.167.100.100:443: done writing
[2025-12-13T09:16:44.831837096Z TRACE quiche_apps::client] timed out
[2025-12-13T09:16:44.831854679Z TRACE quiche] fa0c509cd050167e4b82298a175e9e96b9b19f06 draining timeout expired
[2025-12-13T09:16:44.831885236Z TRACE quiche_apps::client] done reading
[2025-12-13T09:16:44.831892620Z INFO  quiche_apps::client] connection closed, recv=7 sent=10 lost=0 retrans=0 sent_bytes=894 recv_bytes=3824 lost_bytes=0 [local_addr=0.0.0.0:38192 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=7 sent=10 lost=0 retrans=0 rtt=35.303974ms min_rtt=Some(30.801828ms) rttvar=9.898764ms cwnd=13500 sent_bytes=894 recv_bytes=3824 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=13138]