[2025-04-30T20:01:00.356739812Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T20:01:00.357016130Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:42556 with scid d43471519edc81a815742862918cb5c422947baa
[2025-04-30T20:01:00.357056115Z TRACE quiche::tls] d43471519edc81a815742862918cb5c422947baa write message lvl=Initial len=266
[2025-04-30T20:01:00.357074950Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Initial version=1 dcid=c12841aacd275976f01f087a647de3a1 scid=d43471519edc81a815742862918cb5c422947baa len=270 pn=0 
[2025-04-30T20:01:00.357077946Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm CRYPTO off=0 len=266
[2025-04-30T20:01:00.357091892Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=998.93063ms 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: 457, tv_nsec: 692858216 }, first_sent_time: Instant { tv_sec: 457, tv_nsec: 692858216 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 457, tv_nsec: 692858216 }, next_time: Instant { tv_sec: 457, tv_nsec: 692858216 }, 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-04-30T20:01:00.357151143Z TRACE quiche_apps::client] written 1200
[2025-04-30T20:01:00.395823669Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:00.395835842Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Initial version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c token= len=1158 pn=0 
[2025-04-30T20:01:00.395848786Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=0 len=90
[2025-04-30T20:01:00.395933855Z TRACE quiche::tls] d43471519edc81a815742862918cb5c422947baa set write secret lvl=Handshake
[2025-04-30T20:01:00.395941881Z TRACE quiche::tls] d43471519edc81a815742862918cb5c422947baa set read secret lvl=Handshake
[2025-04-30T20:01:00.395958973Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm PADDING len=1044
[2025-04-30T20:01:00.395979631Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1200 bytes
[2025-04-30T20:01:00.395986614Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:00.395990101Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:00.396009407Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Initial version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=1 
[2025-04-30T20:01:00.396014106Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=21 blocks=[0..0] ecn_counts=None
[2025-04-30T20:01:00.396023213Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=959.99959ms 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: 457, tv_nsec: 692858216 }, first_sent_time: Instant { tv_sec: 457, tv_nsec: 692858216 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 457, tv_nsec: 731834832 }, next_time: Instant { tv_sec: 457, tv_nsec: 731834832 }, 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-04-30T20:01:00.396069720Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:00.396075752Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:00.396086522Z TRACE quiche_apps::client] got 211 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:00.396092263Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=170 pn=0 
[2025-04-30T20:01:00.396100819Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=0 len=146
[2025-04-30T20:01:00.396124974Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 211 bytes
[2025-04-30T20:01:00.396132187Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:00.396135263Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:00.396152856Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=2 
[2025-04-30T20:01:00.396155601Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=7 blocks=[0..0] ecn_counts=None
[2025-04-30T20:01:00.396162765Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=959.860108ms 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: 457, tv_nsec: 692858216 }, first_sent_time: Instant { tv_sec: 457, tv_nsec: 692858216 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 457, tv_nsec: 731982268 }, next_time: Instant { tv_sec: 457, tv_nsec: 731982268 }, 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-04-30T20:01:00.396198732Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa dropped epoch 0 state
[2025-04-30T20:01:00.396225101Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:00.396231303Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:00.396996448Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:00.397004102Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=1175 pn=1 
[2025-04-30T20:01:00.397010604Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=146 len=1150
[2025-04-30T20:01:00.397023088Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:00.397026855Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:00.397029249Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:00.397037324Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=3 
[2025-04-30T20:01:00.397046091Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=4 blocks=[0..1] ecn_counts=None
[2025-04-30T20:01:00.397052884Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=998.094112ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 457, tv_nsec: 732867448 }, first_sent_time: Instant { tv_sec: 457, tv_nsec: 732867448 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 457, tv_nsec: 732867448 }, next_time: Instant { tv_sec: 457, tv_nsec: 732867448 }, 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-04-30T20:01:00.397086216Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:00.397092217Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:00.397995107Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:00.398005887Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=1175 pn=2 
[2025-04-30T20:01:00.398013101Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=1296 len=1150
[2025-04-30T20:01:00.398025464Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:00.398029211Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:00.398031655Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:00.398039780Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=4 
[2025-04-30T20:01:00.398042456Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=4 blocks=[0..2] ecn_counts=None
[2025-04-30T20:01:00.398049088Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=997.097907ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 457, tv_nsec: 733869894 }, first_sent_time: Instant { tv_sec: 457, tv_nsec: 733869894 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 457, tv_nsec: 733869894 }, next_time: Instant { tv_sec: 457, tv_nsec: 733869894 }, 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-04-30T20:01:00.398083583Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:00.398095184Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:00.398986435Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:00.398996454Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=1175 pn=3 
[2025-04-30T20:01:00.399003036Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=2446 len=1150
[2025-04-30T20:01:00.399014628Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:00.399018285Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:00.399020670Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:00.399028714Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=5 
[2025-04-30T20:01:00.399031329Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=4 blocks=[0..3] ecn_counts=None
[2025-04-30T20:01:00.399037671Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=996.109334ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 457, tv_nsec: 734858818 }, first_sent_time: Instant { tv_sec: 457, tv_nsec: 734858818 }, end_of_app_limited: 1, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 457, tv_nsec: 734858818 }, next_time: Instant { tv_sec: 457, tv_nsec: 734858818 }, 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-04-30T20:01:00.399070212Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:00.399076163Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:00.399989589Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:00.400000189Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=1175 pn=4 
[2025-04-30T20:01:00.400007172Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=3596 len=1150
[2025-04-30T20:01:00.400019275Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:00.400023062Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:00.400025497Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:00.400033351Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=6 
[2025-04-30T20:01:00.400035996Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=4 blocks=[0..4] ecn_counts=None
[2025-04-30T20:01:00.400042879Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=995.104106ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 457, tv_nsec: 735863695 }, first_sent_time: Instant { tv_sec: 457, tv_nsec: 735863695 }, end_of_app_limited: 1, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 457, tv_nsec: 735863695 }, next_time: Instant { tv_sec: 457, tv_nsec: 735863695 }, 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-04-30T20:01:00.400082524Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:00.400088735Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.397129435Z TRACE quiche_apps::client] timed out
[2025-04-30T20:01:01.397145105Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa loss detection timeout expired
[2025-04-30T20:01:01.397149493Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=1.997994189s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 457, tv_nsec: 735863695 }, first_sent_time: Instant { tv_sec: 457, tv_nsec: 735863695 }, end_of_app_limited: 1, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 457, tv_nsec: 735863695 }, next_time: Instant { tv_sec: 457, tv_nsec: 735863695 }, 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-04-30T20:01:01.397164772Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.397204456Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=9 pn=7 
[2025-04-30T20:01:01.397207812Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=124650 blocks=[0..4] ecn_counts=None
[2025-04-30T20:01:01.397212140Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm PING mtu_probe=None
[2025-04-30T20:01:01.397224884Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=1.997964163s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 458, tv_nsec: 733024320 }, next_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, 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-04-30T20:01:01.397285879Z TRACE quiche_apps::client] written 67 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.397295557Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.429250030Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.429267152Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=1175 pn=5 
[2025-04-30T20:01:01.429278022Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=4746 len=1150
[2025-04-30T20:01:01.429296777Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:01.429302107Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.429305423Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.429316715Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=8 
[2025-04-30T20:01:01.429320572Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=6 blocks=[0..5] ecn_counts=None
[2025-04-30T20:01:01.429330090Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=1.965858737s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, end_of_app_limited: 6, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 458, tv_nsec: 765144964 }, next_time: Instant { tv_sec: 458, tv_nsec: 765144964 }, 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-04-30T20:01:01.429384742Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.429396214Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.430178791Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.430186566Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=1175 pn=6 
[2025-04-30T20:01:01.430193258Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=5896 len=1150
[2025-04-30T20:01:01.430204720Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:01.430208366Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.430210791Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.430218195Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=9 
[2025-04-30T20:01:01.430220970Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=4 blocks=[0..6] ecn_counts=None
[2025-04-30T20:01:01.430227162Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=1.964961815s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, end_of_app_limited: 6, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 458, tv_nsec: 766048819 }, next_time: Instant { tv_sec: 458, tv_nsec: 766048819 }, 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-04-30T20:01:01.430269331Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.430277746Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.431176371Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.431185038Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=1175 pn=7 
[2025-04-30T20:01:01.431191419Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=7046 len=1150
[2025-04-30T20:01:01.431201839Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:01.431205385Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.431207750Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.431215194Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=10 
[2025-04-30T20:01:01.431217849Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=3 blocks=[0..7] ecn_counts=None
[2025-04-30T20:01:01.431245060Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=1.963943847s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, end_of_app_limited: 6, last_sent_packet: 10, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 458, tv_nsec: 767045828 }, next_time: Instant { tv_sec: 458, tv_nsec: 767045828 }, 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-04-30T20:01:01.431282019Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.431290595Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.432184351Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.432192627Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=1175 pn=8 
[2025-04-30T20:01:01.432198558Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=8196 len=1150
[2025-04-30T20:01:01.432214658Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:01.432218465Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.432220779Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.432228213Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=5 pn=11 
[2025-04-30T20:01:01.432230788Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=4 blocks=[0..8] ecn_counts=None
[2025-04-30T20:01:01.432236910Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=1.962952138s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 733024320 }, end_of_app_limited: 6, last_sent_packet: 11, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 458, tv_nsec: 768058917 }, next_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, 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-04-30T20:01:01.432271935Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.432280221Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.432661165Z TRACE quiche_apps::client] got 584 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.432666304Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=543 pn=9 
[2025-04-30T20:01:01.432671865Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=9346 len=518
[2025-04-30T20:01:01.432805425Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 584 bytes
[2025-04-30T20:01:01.432811347Z TRACE quiche_apps::client] got 145 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.432814773Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=104 pn=10 
[2025-04-30T20:01:01.432819322Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=9864 len=79
[2025-04-30T20:01:01.433149681Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 145 bytes
[2025-04-30T20:01:01.433156955Z TRACE quiche_apps::client] got 101 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.433160491Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=60 pn=11 
[2025-04-30T20:01:01.433165050Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=9943 len=36
[2025-04-30T20:01:01.433215905Z TRACE quiche::tls] d43471519edc81a815742862918cb5c422947baa write message lvl=Handshake len=36
[2025-04-30T20:01:01.433219311Z TRACE quiche::tls] d43471519edc81a815742862918cb5c422947baa set write secret lvl=OneRTT
[2025-04-30T20:01:01.433227116Z TRACE quiche::tls] d43471519edc81a815742862918cb5c422947baa set read secret lvl=OneRTT
[2025-04-30T20:01:01.433239930Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa 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(c12841aacd275976f01f087a647de3a1), max_idle_timeout: 120000, stateless_reset_token: Some(110944719876250093514445801517766023149), max_udp_payload_size: 1500, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 16777216, initial_max_stream_data_bidi_remote: 16777216, initial_max_stream_data_uni: 16777216, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(dc5efefe82e45cb23a6f412c), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T20:01:01.433264576Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 101 bytes
[2025-04-30T20:01:01.433269926Z TRACE quiche_apps::client] got 66 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.433273373Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Handshake version=1 dcid=d43471519edc81a815742862918cb5c422947baa scid=dc5efefe82e45cb23a6f412c len=25 pn=12 
[2025-04-30T20:01:01.433278112Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm ACK delay=52 blocks=[7..7] ecn_counts=None
[2025-04-30T20:01:01.433281959Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa packet newly acked 7
[2025-04-30T20:01:01.433291367Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 66 bytes
[2025-04-30T20:01:01.433294833Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.433297157Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.433308949Z DEBUG quiche_apps::common] sending HTTP request "GET /tcmwrhtlcc\r\n"
[2025-04-30T20:01:01.433360105Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Handshake version=1 dcid=dc5efefe82e45cb23a6f412c scid=d43471519edc81a815742862918cb5c422947baa len=45 pn=12 
[2025-04-30T20:01:01.433363732Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=10 blocks=[5..12] ecn_counts=None
[2025-04-30T20:01:01.433366577Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm CRYPTO off=0 len=36
[2025-04-30T20:01:01.433373671Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=108.228582ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=103 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 103, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 768058917 }, next_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, 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-04-30T20:01:01.433387176Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13397 out_len=1247 left=1217
[2025-04-30T20:01:01.433394389Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=302 pn=13 
[2025-04-30T20:01:01.433396904Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[fb, 75, 9a, 73, 07, a9, e2, c3, 73, ac, 10, 64, 5d, 57, 23, 6b, af, b4, 42, da] reset_token=[7a, d3, 24, b2, ea, fc, 44, 48, b5, 52, 9d, 37, e8, 5e, 34, 3b]
[2025-04-30T20:01:01.433407895Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[62, 8e, ea, 46, cb, da, ce, af, 52, f2, c4, d8, 22, 36, 74, 64, a6, 97, da, 3f] reset_token=[2f, 67, 23, d1, 07, ce, 3e, 8d, e2, 6d, 6b, e6, f0, 04, 8f, 58]
[2025-04-30T20:01:01.433412995Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[8f, b1, f7, fb, ef, e2, 8c, 16, 1f, e5, 52, 73, bb, 58, 79, 9d, 8a, ba, eb, 62] reset_token=[c4, bc, 1e, ae, 3c, 45, 63, 38, b9, 8d, d7, e3, 3c, 56, 06, 3d]
[2025-04-30T20:01:01.433417934Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[3e, 0b, 5b, 5a, 30, a3, 1a, f7, 63, 0c, 67, 43, 29, e4, b9, 8e, 0a, e8, 6b, d3] reset_token=[42, dc, 0f, 45, 8a, 37, a8, b1, f6, e6, bf, fa, 56, 88, 22, a1]
[2025-04-30T20:01:01.433423013Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[9b, f4, ce, 3e, 61, 04, 1e, 56, 83, 7b, 30, 35, e2, f1, 8f, cb, f1, 8d, d6, 7f] reset_token=[d0, f3, 03, c7, f6, bc, b8, 74, 58, 23, a3, 27, 80, b2, 14, 43]
[2025-04-30T20:01:01.433427822Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[32, 45, 11, b1, 06, 69, 87, ed, 3e, 01, ba, fe, 3f, 6b, bd, 43, e0, e4, 51, 59] reset_token=[45, 20, cc, 6f, e5, e2, b8, e4, 5c, 6b, 5a, ae, d1, 38, c5, df]
[2025-04-30T20:01:01.433432551Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[42, 30, e9, 22, 2b, 1f, 6d, 7c, b8, da, 99, f1, bc, 19, 35, 42, f5, 0f, 17, 95] reset_token=[6d, 73, 52, e8, 53, 54, fc, 6e, 17, 2b, d0, 6b, 37, 22, 66, 08]
[2025-04-30T20:01:01.433437280Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T20:01:01.433446077Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=108.156217ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=435 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 769187984 }, next_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, max_datagram_size: 1350, last_packet_size: None, iv: 930.145µ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-04-30T20:01:01.433485921Z TRACE quiche_apps::client] written 435 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.433493365Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.465261774Z TRACE quiche_apps::client] got 42 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.465281161Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=21 pn=0 
[2025-04-30T20:01:01.465292692Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm HANDSHAKE_DONE
[2025-04-30T20:01:01.465300397Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa dropped epoch 1 state
[2025-04-30T20:01:01.465318911Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 42 bytes
[2025-04-30T20:01:01.465326075Z TRACE quiche_apps::client] got 52 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.465330403Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=31 pn=4 
[2025-04-30T20:01:01.465337126Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm NEW_TOKEN len=9
[2025-04-30T20:01:01.465343818Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 52 bytes
[2025-04-30T20:01:01.465348236Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.465351482Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.465360419Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T20:01:01.465365168Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=7 pn=14 
[2025-04-30T20:01:01.465368594Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=3 blocks=[0..0, 4..4] ecn_counts=None
[2025-04-30T20:01:01.465377792Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=101.224231ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 14, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 801191424 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, 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-04-30T20:01:01.465428918Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.465434869Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.465648159Z TRACE quiche_apps::client] got 302 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.465658107Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=281 pn=5 
[2025-04-30T20:01:01.465666152Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=0 len=257
[2025-04-30T20:01:01.465694776Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 302 bytes
[2025-04-30T20:01:01.465700236Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.465703462Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.465712339Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T20:01:01.465716887Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=7 pn=15 
[2025-04-30T20:01:01.465720264Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=6 blocks=[0..0, 4..5] ecn_counts=None
[2025-04-30T20:01:01.465729471Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=100.872662ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 15, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 801191424 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, 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-04-30T20:01:01.465783031Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.465788962Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.465880745Z TRACE quiche_apps::client] got 303 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.465892908Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=282 pn=6 
[2025-04-30T20:01:01.465904118Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm CRYPTO off=257 len=257
[2025-04-30T20:01:01.465937591Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 303 bytes
[2025-04-30T20:01:01.465949564Z TRACE quiche_apps::client] got 67 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.465954343Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa dropped invalid packet
[2025-04-30T20:01:01.465957829Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 67 bytes
[2025-04-30T20:01:01.465962117Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.465965213Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.465974360Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T20:01:01.465978789Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=7 pn=16 
[2025-04-30T20:01:01.465982115Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=10 blocks=[0..0, 4..6] ecn_counts=None
[2025-04-30T20:01:01.465992194Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=100.609939ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 16, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 801191424 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, 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-04-30T20:01:01.466048299Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.466055953Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.466067014Z TRACE quiche_apps::client] got 73 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.466072234Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=52 pn=7 
[2025-04-30T20:01:01.466082673Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[f3, a4, c9, 3f, ea, c1, 05, eb, 9a, c3, 0f, 8e] reset_token=[4a, 79, 7b, ef, 60, 0d, 08, f0, 6e, 15, 22, 36, 4b, 4f, c4, e9]
[2025-04-30T20:01:01.466096810Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 73 bytes
[2025-04-30T20:01:01.466104544Z TRACE quiche_apps::client] got 73 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.466109774Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=52 pn=8 
[2025-04-30T20:01:01.466115705Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[93, cc, 88, 85, 3b, 2d, fd, 31, f2, 73, 51, eb] reset_token=[d4, ba, af, c5, 59, 7f, 9b, 4d, b3, 85, bd, 5b, a5, db, c4, 20]
[2025-04-30T20:01:01.466121516Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 73 bytes
[2025-04-30T20:01:01.466124852Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.466127237Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.466133268Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T20:01:01.466136424Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=7 pn=17 
[2025-04-30T20:01:01.466138869Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=2 blocks=[0..0, 4..8] ecn_counts=None
[2025-04-30T20:01:01.466160699Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=100.440822ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 17, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 801191424 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, 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-04-30T20:01:01.466223156Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.466227905Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.467131459Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.467139033Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=1195 pn=9 
[2025-04-30T20:01:01.467150535Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm STREAM id=0 off=0 len=1171 fin=false
[2025-04-30T20:01:01.467157959Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:01.467161606Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.467163980Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.467184078Z TRACE quiche_apps::common] received 1171 bytes
[2025-04-30T20:01:01.467189608Z TRACE quiche_apps::common] stream 0 has 1171 bytes (fin? false)
[2025-04-30T20:01:01.467196832Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T20:01:01.467200098Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=7 pn=18 
[2025-04-30T20:01:01.467202553Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=7 blocks=[0..0, 4..9] ecn_counts=None
[2025-04-30T20:01:01.467209175Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=99.393098ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 18, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 801191424 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, 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-04-30T20:01:01.467241676Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.467245864Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.468127637Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.468134800Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=1195 pn=10 
[2025-04-30T20:01:01.468141132Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm STREAM id=0 off=1171 len=1169 fin=false
[2025-04-30T20:01:01.468148265Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:01.468151672Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.468154056Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.468158184Z TRACE quiche_apps::common] received 1169 bytes
[2025-04-30T20:01:01.468160468Z TRACE quiche_apps::common] stream 0 has 1169 bytes (fin? false)
[2025-04-30T20:01:01.468182730Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T20:01:01.468189042Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=7 pn=19 
[2025-04-30T20:01:01.468191507Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=6 blocks=[0..0, 4..10] ecn_counts=None
[2025-04-30T20:01:01.468198169Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=98.404094ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 19, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 801191424 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, 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-04-30T20:01:01.468235249Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.468239517Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.469125407Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.469132951Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=1195 pn=11 
[2025-04-30T20:01:01.469139203Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm STREAM id=0 off=2340 len=1169 fin=false
[2025-04-30T20:01:01.469146296Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:01.469149773Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.469152107Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.469156165Z TRACE quiche_apps::common] received 1169 bytes
[2025-04-30T20:01:01.469158399Z TRACE quiche_apps::common] stream 0 has 1169 bytes (fin? false)
[2025-04-30T20:01:01.469164671Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T20:01:01.469186792Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=7 pn=20 
[2025-04-30T20:01:01.469189417Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=4 blocks=[0..0, 4..11] ecn_counts=None
[2025-04-30T20:01:01.469196170Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=97.406143ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 20, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 801191424 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, 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-04-30T20:01:01.469233440Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.469237758Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.470121695Z TRACE quiche_apps::client] got 1216 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.470129330Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=1195 pn=12 
[2025-04-30T20:01:01.470135651Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm STREAM id=0 off=3509 len=1169 fin=false
[2025-04-30T20:01:01.470142655Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 1216 bytes
[2025-04-30T20:01:01.470146161Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.470148495Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.470152573Z TRACE quiche_apps::common] received 1169 bytes
[2025-04-30T20:01:01.470154787Z TRACE quiche_apps::common] stream 0 has 1169 bytes (fin? false)
[2025-04-30T20:01:01.470161059Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T20:01:01.470164145Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=7 pn=21 
[2025-04-30T20:01:01.470182359Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm ACK delay=4 blocks=[0..0, 4..12] ecn_counts=None
[2025-04-30T20:01:01.470192257Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=96.410035ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 12, last_sent_packet: 21, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 801191424 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, 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-04-30T20:01:01.470224217Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.470228395Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.470534158Z TRACE quiche_apps::client] got 489 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.470542124Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx pkt Short dcid=d43471519edc81a815742862918cb5c422947baa key_phase=false len=468 pn=13 
[2025-04-30T20:01:01.470548165Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa rx frm STREAM id=0 off=4678 len=442 fin=true
[2025-04-30T20:01:01.470555398Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 489 bytes
[2025-04-30T20:01:01.470558905Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.470561219Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.470565267Z TRACE quiche_apps::common] received 442 bytes
[2025-04-30T20:01:01.470567511Z TRACE quiche_apps::common] stream 0 has 442 bytes (fin? true)
[2025-04-30T20:01:01.470569785Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T20:01:01.470577299Z INFO  quiche_apps::common] 1/1 response(s) received in 1.113420325s, closing...
[2025-04-30T20:01:01.470584062Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1320
[2025-04-30T20:01:01.470587549Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx pkt Short dcid=dc5efefe82e45cb23a6f412c key_phase=false len=10 pn=22 
[2025-04-30T20:01:01.470590013Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T20:01:01.470596696Z TRACE quiche::recovery::congestion::recovery] d43471519edc81a815742862918cb5c422947baa timer=133.234123ms latest_rtt=36.083194ms srtt=36.083194ms min_rtt=36.083194ms rttvar=18.041597ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=372 app_limited=true congestion_recovery_start_time=None Rate { delivered: 67, delivered_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, first_sent_time: Instant { tv_sec: 458, tv_nsec: 768058917 }, end_of_app_limited: 21, last_sent_packet: 22, largest_acked: 7, rate_sample: RateSample { delivery_rate: 1856, is_app_limited: true, interval: 36.083194ms, delivered: 67, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 458, tv_nsec: 733024320 }), send_elapsed: 0ns, ack_elapsed: 36.083194ms, rtt: 36.083194ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 467669, last_update: Instant { tv_sec: 458, tv_nsec: 806416530 }, next_time: Instant { tv_sec: 458, tv_nsec: 802121569 }, max_datagram_size: 1350, last_packet_size: None, iv: 85.531µ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-04-30T20:01:01.470630109Z TRACE quiche_apps::client] written 40 bytes from 0.0.0.0:42556 to 193.167.100.100:443
[2025-04-30T20:01:01.470633775Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.489551793Z TRACE quiche_apps::client] got 47 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.489563535Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 47 bytes
[2025-04-30T20:01:01.489568123Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.489571349Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.489576539Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.501195992Z TRACE quiche_apps::client] got 45 bytes from 193.167.100.100:443 to 0.0.0.0:42556
[2025-04-30T20:01:01.501211591Z TRACE quiche_apps::client] 0.0.0.0:42556: processed 45 bytes
[2025-04-30T20:01:01.501217322Z TRACE quiche_apps::client] 0.0.0.0:42556: recv() would block
[2025-04-30T20:01:01.501220739Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.501226219Z TRACE quiche_apps::client] 0.0.0.0:42556 -> 193.167.100.100:443: done writing
[2025-04-30T20:01:01.796590954Z TRACE quiche_apps::client] timed out
[2025-04-30T20:01:01.796608307Z TRACE quiche] d43471519edc81a815742862918cb5c422947baa draining timeout expired
[2025-04-30T20:01:01.796643373Z TRACE quiche_apps::client] done reading
[2025-04-30T20:01:01.796648122Z INFO  quiche_apps::client] connection closed, recv=25 sent=23 lost=5 retrans=0 sent_bytes=1802 recv_bytes=18233 lost_bytes=0 [local_addr=0.0.0.0:42556 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=25 sent=23 lost=5 retrans=0 rtt=36.083194ms min_rtt=Some(36.083194ms) rttvar=18.041597ms cwnd=13500 sent_bytes=1802 recv_bytes=18233 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=1856]