[2025-09-12T05:04:55.528044755Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T05:04:55.528321592Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:42482 with scid 5510eb45840e579731557c83ad9358f4e8e365af
[2025-09-12T05:04:55.528360736Z TRACE quiche::tls] 5510eb45840e579731557c83ad9358f4e8e365af write message lvl=Initial len=266
[2025-09-12T05:04:55.528376785Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Initial version=1 dcid=71f2b7f24e4fc1dcaefe8150c8bdd03d scid=5510eb45840e579731557c83ad9358f4e8e365af len=270 pn=0 
[2025-09-12T05:04:55.528379801Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm CRYPTO off=0 len=266
[2025-09-12T05:04:55.528393086Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=998.934838ms 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: 615, tv_nsec: 526396375 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 526396375 }, 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: 615, tv_nsec: 526396375 }, next_time: Instant { tv_sec: 615, tv_nsec: 526396375 }, 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:04:55.528452627Z TRACE quiche_apps::client] written 1200
[2025-09-12T05:04:55.566474938Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:55.566491159Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Initial version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb token= len=116 pn=0 
[2025-09-12T05:04:55.566502410Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm ACK delay=56 blocks=[0..0] ecn_counts=None
[2025-09-12T05:04:55.566508240Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af packet newly acked 0
[2025-09-12T05:04:55.566513350Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=0 len=90
[2025-09-12T05:04:55.566613136Z TRACE quiche::tls] 5510eb45840e579731557c83ad9358f4e8e365af set write secret lvl=Handshake
[2025-09-12T05:04:55.566622694Z TRACE quiche::tls] 5510eb45840e579731557c83ad9358f4e8e365af set read secret lvl=Handshake
[2025-09-12T05:04:55.566649234Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Handshake version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb len=1159 pn=0 
[2025-09-12T05:04:55.566656297Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=0 len=1138
[2025-09-12T05:04:55.566674040Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:55.566679170Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:55.566682216Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:55.566701000Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Initial version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=5 pn=1 
[2025-09-12T05:04:55.566704557Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=26 blocks=[0..0] ecn_counts=None
[2025-09-12T05:04:55.566712873Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=114.254515ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 564737002 }, next_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, 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:04:55.566742107Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=5 pn=2 
[2025-09-12T05:04:55.566745584Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=11 blocks=[0..0] ecn_counts=None
[2025-09-12T05:04:55.566751786Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=114.215692ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 564737002 }, next_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, 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:04:55.566764058Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af dropped epoch 0 state
[2025-09-12T05:04:55.566796278Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:55.566802620Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:55.567559995Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:55.567572909Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Handshake version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb len=1313 pn=1 
[2025-09-12T05:04:55.567580824Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=1138 len=1291
[2025-09-12T05:04:55.567594900Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:55.567598988Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:55.567601392Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:55.567610269Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=5 pn=3 
[2025-09-12T05:04:55.567613054Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=4 blocks=[0..1] ecn_counts=None
[2025-09-12T05:04:55.567626088Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=113.547444ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 564737002 }, next_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, 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:04:55.567662817Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:55.567667877Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:55.568660701Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:55.568673425Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Handshake version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb len=1313 pn=2 
[2025-09-12T05:04:55.568681370Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=2429 len=1291
[2025-09-12T05:04:55.568695196Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:55.568699293Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:55.568701758Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:55.568710374Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=5 pn=4 
[2025-09-12T05:04:55.568713179Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=4 blocks=[0..2] ecn_counts=None
[2025-09-12T05:04:55.568720412Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=112.45312ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 564737002 }, next_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, 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:04:55.568756680Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:55.568761549Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:55.681957675Z TRACE quiche_apps::client] timed out
[2025-09-12T05:04:55.681971471Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af loss detection timeout expired
[2025-09-12T05:04:55.681976059Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=228.953079ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 564737002 }, next_time: Instant { tv_sec: 615, tv_nsec: 564737042 }, 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:04:55.681994674Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:55.682012688Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=7 pn=5 
[2025-09-12T05:04:55.682016875Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=14167 blocks=[0..2] ecn_counts=None
[2025-09-12T05:04:55.682021494Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm PING mtu_probe=None
[2025-09-12T05:04:55.682033146Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=228.925988ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=61 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 61, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 680068445 }, next_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, max_datagram_size: 1350, last_packet_size: Some(61), 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:04:55.682081957Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:55.682089040Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:55.911369817Z TRACE quiche_apps::client] timed out
[2025-09-12T05:04:55.911383712Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af loss detection timeout expired
[2025-09-12T05:04:55.911388231Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=228.529482ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=61 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 61, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 680068445 }, next_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, max_datagram_size: 1350, last_packet_size: Some(61), 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:04:55.911415311Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:55.911433966Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=9 pn=6 
[2025-09-12T05:04:55.911438054Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=42845 blocks=[0..2] ecn_counts=None
[2025-09-12T05:04:55.911442703Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm PING mtu_probe=None
[2025-09-12T05:04:55.911455657Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=457.883365ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=124 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 909489363 }, next_time: Instant { tv_sec: 615, tv_nsec: 909489363 }, max_datagram_size: 1350, last_packet_size: Some(63), 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:04:55.911501903Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:55.911514517Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=9 pn=7 
[2025-09-12T05:04:55.911518364Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=42855 blocks=[0..2] ecn_counts=None
[2025-09-12T05:04:55.911522191Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm PING mtu_probe=None
[2025-09-12T05:04:55.911529595Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=457.894977ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 909489363 }, next_time: Instant { tv_sec: 615, tv_nsec: 909489363 }, max_datagram_size: 1350, last_packet_size: Some(63), 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:04:55.911581682Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:55.911592853Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.370106760Z TRACE quiche_apps::client] timed out
[2025-09-12T05:04:56.370121578Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af loss detection timeout expired
[2025-09-12T05:04:56.370126137Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=457.215964ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 442218, last_update: Instant { tv_sec: 615, tv_nsec: 909489363 }, next_time: Instant { tv_sec: 615, tv_nsec: 909489363 }, max_datagram_size: 1350, last_packet_size: Some(63), 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:04:56.370144541Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.370162745Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=9 pn=8 
[2025-09-12T05:04:56.370167003Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=100186 blocks=[0..2] ecn_counts=None
[2025-09-12T05:04:56.370171622Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm PING mtu_probe=None
[2025-09-12T05:04:56.370183063Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=915.803029ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=250 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 442218, last_update: Instant { tv_sec: 616, tv_nsec: 368218523 }, next_time: Instant { tv_sec: 616, tv_nsec: 368218523 }, max_datagram_size: 1350, last_packet_size: Some(63), 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:04:56.370241733Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.370262401Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=9 pn=9 
[2025-09-12T05:04:56.370268833Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=100198 blocks=[0..2] ecn_counts=None
[2025-09-12T05:04:56.370274985Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm PING mtu_probe=None
[2025-09-12T05:04:56.370288210Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=915.797258ms latest_rtt=38.159829ms srtt=38.159829ms min_rtt=38.159829ms rttvar=19.079914ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, first_sent_time: Instant { tv_sec: 615, tv_nsec: 680068445 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 38.159829ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 526396375 }), send_elapsed: 0ns, ack_elapsed: 38.159829ms, rtt: 38.159829ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 442218, last_update: Instant { tv_sec: 616, tv_nsec: 368218523 }, next_time: Instant { tv_sec: 616, tv_nsec: 368218523 }, max_datagram_size: 1350, last_packet_size: Some(63), 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:04:56.370332392Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.370343373Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.401926626Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.401967151Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Handshake version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb len=1313 pn=3 
[2025-09-12T05:04:56.401978422Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm ACK delay=7 blocks=[8..9] ecn_counts=None
[2025-09-12T05:04:56.401983993Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af packet newly acked 8
[2025-09-12T05:04:56.401987629Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af packet newly acked 9
[2025-09-12T05:04:56.401992909Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af packet 5 lost on epoch 1
[2025-09-12T05:04:56.401996266Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af packet 6 lost on epoch 1
[2025-09-12T05:04:56.401999482Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af packet 7 lost on epoch 1
[2025-09-12T05:04:56.402026201Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=3720 len=1286
[2025-09-12T05:04:56.402048914Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:56.402054545Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.402057831Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.402069773Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=5 pn=10 
[2025-09-12T05:04:56.402081525Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=12 blocks=[3..3] ecn_counts=None
[2025-09-12T05:04:56.402091664Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=none latest_rtt=31.813693ms srtt=37.366561ms min_rtt=31.813693ms rttvar=15.896469ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 400032216 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 616, tv_nsec: 368218523 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 368218523 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 9, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 719.963771ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 680068445 }), send_elapsed: 688.150078ms, ack_elapsed: 719.963771ms, rtt: 31.813693ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 316124, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 368218523 }, max_datagram_size: 1350, last_packet_size: None, iv: 398.578µ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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.402145164Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.402156385Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.403017593Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.403032942Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Handshake version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb len=1313 pn=4 
[2025-09-12T05:04:56.403042470Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=5006 len=1291
[2025-09-12T05:04:56.403082365Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:56.403087715Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.403090800Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.403101901Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=5 pn=11 
[2025-09-12T05:04:56.403105558Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=8 blocks=[3..4] ecn_counts=None
[2025-09-12T05:04:56.403114905Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=none latest_rtt=31.813693ms srtt=37.366561ms min_rtt=31.813693ms rttvar=15.896469ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 400032216 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 9, last_sent_packet: 11, largest_acked: 9, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 719.963771ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 680068445 }), send_elapsed: 688.150078ms, ack_elapsed: 719.963771ms, rtt: 31.813693ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 316124, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.403167824Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.403178705Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.404045003Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.404060321Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Handshake version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb len=1313 pn=5 
[2025-09-12T05:04:56.404070110Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=6297 len=1291
[2025-09-12T05:04:56.404086350Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:56.404091279Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.404094335Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.404104965Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=5 pn=12 
[2025-09-12T05:04:56.404108642Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=5 blocks=[3..5] ecn_counts=None
[2025-09-12T05:04:56.404117688Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=none latest_rtt=31.813693ms srtt=37.366561ms min_rtt=31.813693ms rttvar=15.896469ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 400032216 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 9, last_sent_packet: 12, largest_acked: 9, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 719.963771ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 680068445 }), send_elapsed: 688.150078ms, ack_elapsed: 719.963771ms, rtt: 31.813693ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 316124, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.404164726Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.404174685Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.405144837Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.405160326Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Handshake version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb len=1313 pn=6 
[2025-09-12T05:04:56.405169994Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=7588 len=1291
[2025-09-12T05:04:56.405187687Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:56.405192777Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.405195873Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.405206382Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=5 pn=13 
[2025-09-12T05:04:56.405209969Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=5 blocks=[3..6] ecn_counts=None
[2025-09-12T05:04:56.405219066Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=none latest_rtt=31.813693ms srtt=37.366561ms min_rtt=31.813693ms rttvar=15.896469ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 400032216 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 9, last_sent_packet: 13, largest_acked: 9, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 719.963771ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 680068445 }), send_elapsed: 688.150078ms, ack_elapsed: 719.963771ms, rtt: 31.813693ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 316124, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.405272405Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.405283116Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.406206961Z TRACE quiche_apps::client] got 1306 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.406214986Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Handshake version=1 dcid=5510eb45840e579731557c83ad9358f4e8e365af scid=322970416c76f0fb len=1119 pn=7 
[2025-09-12T05:04:56.406221609Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=8879 len=1097
[2025-09-12T05:04:56.406701966Z TRACE quiche::tls] 5510eb45840e579731557c83ad9358f4e8e365af write message lvl=Handshake len=36
[2025-09-12T05:04:56.406706194Z TRACE quiche::tls] 5510eb45840e579731557c83ad9358f4e8e365af set write secret lvl=OneRTT
[2025-09-12T05:04:56.406714529Z TRACE quiche::tls] 5510eb45840e579731557c83ad9358f4e8e365af set read secret lvl=OneRTT
[2025-09-12T05:04:56.406727283Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af 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(71f2b7f24e4fc1dcaefe8150c8bdd03d), max_idle_timeout: 30000, stateless_reset_token: Some(309525041105691431372457603467992942839), 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(322970416c76f0fb), retry_source_connection_id: None, max_datagram_frame_size: Some(65535), unknown_params: None }
[2025-09-12T05:04:56.406749214Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Short dcid=5510eb45840e579731557c83ad9358f4e8e365af key_phase=false len=129 pn=0 
[2025-09-12T05:04:56.406755907Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e5, b6, ee, f9, ac, 36, b4, 3b] reset_token=[8e, e3, e7, 07, 93, 4b, d5, d2, 80, 67, 78, 44, 33, 01, d2, b1]
[2025-09-12T05:04:56.406761818Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[4b, d7, 07, 30, 62, 8b, b3, fb] reset_token=[fc, 41, ea, dd, 56, 89, 37, 32, 97, a3, b6, b8, 51, 8b, 60, 59]
[2025-09-12T05:04:56.406766266Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[18, 3a, 34, 3d, c1, 87, 2f, 18] reset_token=[60, f7, c4, b1, bd, e5, df, 92, de, ac, 37, cb, f4, 64, 87, 26]
[2025-09-12T05:04:56.406775283Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[01, 64, 77, 75, 2b, 38, ec, 7b] reset_token=[50, 7f, 72, 87, 22, d0, dd, 21, c0, 14, dc, 8d, da, f3, 79, 3c]
[2025-09-12T05:04:56.406782706Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1306 bytes
[2025-09-12T05:04:56.406787986Z TRACE quiche_apps::client] got 122 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.406791032Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Short dcid=5510eb45840e579731557c83ad9358f4e8e365af key_phase=false len=101 pn=1 
[2025-09-12T05:04:56.406795761Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[7d, 2c, 9e, df, 33, 2d, 26, 54] reset_token=[51, d5, c4, fb, f1, 09, c9, 98, d5, 67, 8a, db, 27, 65, 9a, e7]
[2025-09-12T05:04:56.406800019Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[72, f5, da, f0, 31, 54, fa, 1e] reset_token=[b9, 48, ba, 80, 52, 6f, 68, 0a, c6, 7a, 6e, 73, 44, ca, 63, 90]
[2025-09-12T05:04:56.406804337Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[34, 56, 00, ad, 1d, 24, 1a, cb] reset_token=[53, 49, 4f, c1, a8, e5, dd, 1a, 42, 41, 9a, 8a, d9, ba, 0a, 55]
[2025-09-12T05:04:56.406810057Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 122 bytes
[2025-09-12T05:04:56.406813374Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.406815698Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.406823342Z DEBUG quiche_apps::common] sending HTTP request "GET /tvyklhxaox\r\n"
[2025-09-12T05:04:56.406870380Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Handshake version=1 dcid=322970416c76f0fb scid=5510eb45840e579731557c83ad9358f4e8e365af len=46 pn=14 
[2025-09-12T05:04:56.406873967Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=81 blocks=[3..7] ecn_counts=None
[2025-09-12T05:04:56.406877233Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm CRYPTO off=0 len=36
[2025-09-12T05:04:56.406886019Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=100.929403ms latest_rtt=31.813693ms srtt=37.366561ms min_rtt=31.813693ms rttvar=15.896469ms cwnd=9450 ssthresh=9450 bytes_in_flight=100 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 436645033 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 9, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 719.963771ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 680068445 }), send_elapsed: 688.150078ms, ack_elapsed: 719.963771ms, rtt: 31.813693ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 316124, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.406905666Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Short dcid=322970416c76f0fb key_phase=false len=187 pn=15 
[2025-09-12T05:04:56.406908532Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=13 blocks=[0..1] ecn_counts=None
[2025-09-12T05:04:56.406911177Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[4a, 54, 70, 55, 03, e2, 75, d1, 95, 71, 05, fa, ce, b4, 61, d6, f2, c3, e9, 08] reset_token=[76, e5, 2b, 90, 5c, 1f, c3, 53, b0, 63, 0f, 54, 6f, 6b, 07, a8]
[2025-09-12T05:04:56.406921265Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[c2, 2c, b2, e0, 6c, 86, ca, c7, 8d, 92, 54, 3f, 27, 8f, c3, 1a, 4c, d6, 4e, 8c] reset_token=[bf, 2b, 09, 70, eb, be, 26, 82, 1a, d1, 22, a7, 9e, 1f, cf, 37]
[2025-09-12T05:04:56.406926435Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[0a, 30, 0e, cd, 6a, 0a, 5a, 87, 9b, 01, f0, 6e, 5f, 3f, 8d, af, 59, a7, d5, 4e] reset_token=[e3, 58, 71, fa, cf, 0d, 9f, 6d, 4f, e6, c9, 74, a4, 0a, 79, 76]
[2025-09-12T05:04:56.406931665Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[f5, d1, 12, b5, 05, 90, 0d, a6, 1d, 3b, f3, ab, 15, 8b, 65, 3a, 56, 9d, 39, dd] reset_token=[62, a7, 73, 56, f2, b8, 67, c1, bc, d3, 56, 47, 60, 5e, 49, 2b]
[2025-09-12T05:04:56.406963143Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm STREAM id=0 off=0 len=17 fin=true
[2025-09-12T05:04:56.406972691Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=100.842862ms latest_rtt=31.813693ms srtt=37.366561ms min_rtt=31.813693ms rttvar=15.896469ms cwnd=9450 ssthresh=9450 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 436645033 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 9, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 719.963771ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 615, tv_nsec: 680068445 }), send_elapsed: 688.150078ms, ack_elapsed: 719.963771ms, rtt: 31.813693ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 316124, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.407006635Z TRACE quiche_apps::client] written 313 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.407014119Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.437947596Z TRACE quiche_apps::client] got 406 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.437963907Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Short dcid=4a54705503e275d1957105faceb461d6f2c3e908 key_phase=false len=385 pn=2 
[2025-09-12T05:04:56.437971902Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af path ID 0 now see SCID with seq num 1
[2025-09-12T05:04:56.437976671Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm HANDSHAKE_DONE
[2025-09-12T05:04:56.437982702Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af dropped epoch 1 state
[2025-09-12T05:04:56.437986659Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm ACK delay=8 blocks=[15..15] ecn_counts=None
[2025-09-12T05:04:56.437991378Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af packet newly acked 15
[2025-09-12T05:04:56.437997349Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm CRYPTO off=0 len=356
[2025-09-12T05:04:56.438040019Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-09-12T05:04:56.438055648Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 406 bytes
[2025-09-12T05:04:56.438069394Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.438072760Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.438076156Z INFO  quiche_apps::client] Retiring source CID 5510eb45840e579731557c83ad9358f4e8e365af
[2025-09-12T05:04:56.438091084Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Short dcid=322970416c76f0fb key_phase=false len=45 pn=16 
[2025-09-12T05:04:56.438094912Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=15 blocks=[2..2] ecn_counts=None
[2025-09-12T05:04:56.438098859Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=1 conn_id=[ce, 0b, 01, c4, 25, 93, c7, bd, 25, ff, 92, 51, 59, ff, 90, d1, 98, c3, 6a, a6] reset_token=[51, 77, 2d, 86, 2c, 33, 8b, 51, da, a1, c2, 54, 4e, 80, da, 7f]
[2025-09-12T05:04:56.438112735Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=112.619288ms latest_rtt=34.468196ms srtt=36.996264ms min_rtt=31.813693ms rttvar=12.662942ms cwnd=9450 ssthresh=9450 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 467866138 }) Rate { delivered: 672, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 15, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 34.468196ms, delivered: 213, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 616, tv_nsec: 401561577 }), send_elapsed: 0ns, ack_elapsed: 34.468196ms, rtt: 34.468196ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 319288, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.438160043Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.438165964Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.439036490Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.439047000Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Short dcid=4a54705503e275d1957105faceb461d6f2c3e908 key_phase=false len=1329 pn=3 
[2025-09-12T05:04:56.439054173Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm STREAM id=0 off=0 len=1310 fin=false
[2025-09-12T05:04:56.439061938Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:56.439065505Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.439067819Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.439083979Z TRACE quiche_apps::common] received 1310 bytes
[2025-09-12T05:04:56.439086624Z TRACE quiche_apps::common] stream 0 has 1310 bytes (fin? false)
[2025-09-12T05:04:56.439094559Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Short dcid=322970416c76f0fb key_phase=false len=5 pn=17 
[2025-09-12T05:04:56.439097113Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=5 blocks=[2..3] ecn_counts=None
[2025-09-12T05:04:56.439104127Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=111.628036ms latest_rtt=34.468196ms srtt=36.996264ms min_rtt=31.813693ms rttvar=12.662942ms cwnd=9450 ssthresh=9450 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 467866138 }) Rate { delivered: 672, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 15, last_sent_packet: 17, largest_acked: 15, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 34.468196ms, delivered: 213, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 616, tv_nsec: 401561577 }), send_elapsed: 0ns, ack_elapsed: 34.468196ms, rtt: 34.468196ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 319288, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.439138361Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.439143139Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.440142035Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.440152515Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Short dcid=4a54705503e275d1957105faceb461d6f2c3e908 key_phase=false len=1329 pn=4 
[2025-09-12T05:04:56.440159588Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm STREAM id=0 off=1310 len=1308 fin=false
[2025-09-12T05:04:56.440167052Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:56.440170538Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.440172843Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.440176901Z TRACE quiche_apps::common] received 1308 bytes
[2025-09-12T05:04:56.440179165Z TRACE quiche_apps::common] stream 0 has 1308 bytes (fin? false)
[2025-09-12T05:04:56.440186689Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Short dcid=322970416c76f0fb key_phase=false len=5 pn=18 
[2025-09-12T05:04:56.440189233Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=4 blocks=[2..4] ecn_counts=None
[2025-09-12T05:04:56.440195515Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=110.536718ms latest_rtt=34.468196ms srtt=36.996264ms min_rtt=31.813693ms rttvar=12.662942ms cwnd=9450 ssthresh=9450 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 467866138 }) Rate { delivered: 672, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 15, last_sent_packet: 18, largest_acked: 15, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 34.468196ms, delivered: 213, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 616, tv_nsec: 401561577 }), send_elapsed: 0ns, ack_elapsed: 34.468196ms, rtt: 34.468196ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 319288, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.440225541Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.440229368Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.441239566Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.441249584Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Short dcid=4a54705503e275d1957105faceb461d6f2c3e908 key_phase=false len=1329 pn=5 
[2025-09-12T05:04:56.441261296Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm STREAM id=0 off=2618 len=1308 fin=false
[2025-09-12T05:04:56.441268580Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1350 bytes
[2025-09-12T05:04:56.441272076Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.441274420Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.441278438Z TRACE quiche_apps::common] received 1308 bytes
[2025-09-12T05:04:56.441280662Z TRACE quiche_apps::common] stream 0 has 1308 bytes (fin? false)
[2025-09-12T05:04:56.441287906Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Short dcid=322970416c76f0fb key_phase=false len=5 pn=19 
[2025-09-12T05:04:56.441290401Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm ACK delay=4 blocks=[2..5] ecn_counts=None
[2025-09-12T05:04:56.441296392Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=109.435861ms latest_rtt=34.468196ms srtt=36.996264ms min_rtt=31.813693ms rttvar=12.662942ms cwnd=9450 ssthresh=9450 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 467866138 }) Rate { delivered: 672, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 15, last_sent_packet: 19, largest_acked: 15, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 34.468196ms, delivered: 213, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 616, tv_nsec: 401561577 }), send_elapsed: 0ns, ack_elapsed: 34.468196ms, rtt: 34.468196ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 319288, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.441326919Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.441330796Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.442254301Z TRACE quiche_apps::client] got 1238 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.442266173Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Short dcid=4a54705503e275d1957105faceb461d6f2c3e908 key_phase=false len=1217 pn=6 
[2025-09-12T05:04:56.442276031Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm STREAM id=0 off=3926 len=1194 fin=false
[2025-09-12T05:04:56.442283766Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 1238 bytes
[2025-09-12T05:04:56.442288174Z TRACE quiche_apps::client] got 43 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.442291170Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx pkt Short dcid=4a54705503e275d1957105faceb461d6f2c3e908 key_phase=false len=22 pn=7 
[2025-09-12T05:04:56.442294877Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af rx frm STREAM id=0 off=5120 len=0 fin=true
[2025-09-12T05:04:56.442299555Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 43 bytes
[2025-09-12T05:04:56.442302861Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.442305156Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.442310306Z TRACE quiche_apps::common] received 1194 bytes
[2025-09-12T05:04:56.442312520Z TRACE quiche_apps::common] stream 0 has 1194 bytes (fin? true)
[2025-09-12T05:04:56.442314764Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-12T05:04:56.442317018Z INFO  quiche_apps::common] 1/1 response(s) received in 913.860163ms, closing...
[2025-09-12T05:04:56.442329411Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx pkt Short dcid=322970416c76f0fb key_phase=false len=10 pn=20 
[2025-09-12T05:04:56.442332136Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T05:04:56.442339320Z TRACE quiche::recovery::congestion::recovery] 5510eb45840e579731557c83ad9358f4e8e365af timer=112.634446ms latest_rtt=34.468196ms srtt=36.996264ms min_rtt=31.813693ms rttvar=12.662942ms cwnd=9450 ssthresh=9450 bytes_in_flight=107 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 616, tv_nsec: 467866138 }) Rate { delivered: 672, delivered_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, first_sent_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, end_of_app_limited: 19, last_sent_packet: 20, largest_acked: 15, rate_sample: RateSample { bandwidth: 69.81 Kbps, is_app_limited: true, interval: 34.468196ms, delivered: 213, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 616, tv_nsec: 401561577 }), send_elapsed: 0ns, ack_elapsed: 34.468196ms, rtt: 34.468196ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 319288, last_update: Instant { tv_sec: 616, tv_nsec: 400130290 }, next_time: Instant { tv_sec: 616, tv_nsec: 401561577 }, 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=1.9574338205844317 w_max=13500 } 
[2025-09-12T05:04:56.442370959Z TRACE quiche_apps::client] written 36 bytes from 0.0.0.0:42482 to 193.167.100.100:443
[2025-09-12T05:04:56.442374445Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.472761936Z TRACE quiche_apps::client] got 47 bytes from 193.167.100.100:443 to 0.0.0.0:42482
[2025-09-12T05:04:56.472774930Z TRACE quiche_apps::client] 0.0.0.0:42482: processed 47 bytes
[2025-09-12T05:04:56.472780030Z TRACE quiche_apps::client] 0.0.0.0:42482: recv() would block
[2025-09-12T05:04:56.472782945Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.472787714Z TRACE quiche_apps::client] 0.0.0.0:42482 -> 193.167.100.100:443: done writing
[2025-09-12T05:04:56.706072391Z TRACE quiche_apps::client] timed out
[2025-09-12T05:04:56.706086197Z TRACE quiche] 5510eb45840e579731557c83ad9358f4e8e365af draining timeout expired
[2025-09-12T05:04:56.706113668Z TRACE quiche_apps::client] done reading
[2025-09-12T05:04:56.706119158Z INFO  quiche_apps::client] connection closed, recv=17 sent=21 lost=6 retrans=0 sent_bytes=1632 recv_bytes=16615 lost_bytes=187 [local_addr=0.0.0.0:42482 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=17 sent=21 lost=6 retrans=0 rtt=36.996264ms min_rtt=Some(31.813693ms) rttvar=12.662942ms cwnd=9450 sent_bytes=1632 recv_bytes=16615 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=8726]