[2025-04-30T08:44:59.849776839Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T08:44:59.850100703Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:51958 with scid f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8
[2025-04-30T08:44:59.850143633Z TRACE quiche::tls] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 write message lvl=Initial len=266
[2025-04-30T08:44:59.850162608Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Initial version=1 dcid=4e4999fd5a31e3b06269e0af00f9424d scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=270 pn=0 
[2025-04-30T08:44:59.850165854Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm CRYPTO off=0 len=266
[2025-04-30T08:44:59.850180061Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=998.929459ms 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: 434, tv_nsec: 218235759 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 218235759 }, 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: 434, tv_nsec: 218235759 }, next_time: Instant { tv_sec: 434, tv_nsec: 218235759 }, 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-30T08:44:59.850261042Z TRACE quiche_apps::client] written 1200
[2025-04-30T08:44:59.889734850Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:44:59.889763423Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Initial version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 token= len=119 pn=1405802206 
[2025-04-30T08:44:59.889779123Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T08:44:59.889785174Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 packet newly acked 0
[2025-04-30T08:44:59.889791435Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=0 len=90
[2025-04-30T08:44:59.889912371Z TRACE quiche::tls] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 set write secret lvl=Handshake
[2025-04-30T08:44:59.889924754Z TRACE quiche::tls] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 set read secret lvl=Handshake
[2025-04-30T08:44:59.889959820Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=982 pn=1405802206 
[2025-04-30T08:44:59.889966081Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=0 len=958
[2025-04-30T08:44:59.889983644Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:44:59.889988673Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:44:59.889991288Z TRACE quiche_apps::client] done reading
[2025-04-30T08:44:59.890014321Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Initial version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=1 
[2025-04-30T08:44:59.890017297Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=31 blocks=[1405802206..1405802206] ecn_counts=None
[2025-04-30T08:44:59.890025752Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=118.676457ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 258104032 }, next_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, 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-30T08:44:59.890054216Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=2 
[2025-04-30T08:44:59.890057051Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=11 blocks=[1405802206..1405802206] ecn_counts=None
[2025-04-30T08:44:59.890062521Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=118.639819ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 258104032 }, next_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, 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-30T08:44:59.890073221Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 dropped epoch 0 state
[2025-04-30T08:44:59.890121371Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:44:59.890130819Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:44:59.890660276Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:44:59.890665937Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=1151 pn=1405802207 
[2025-04-30T08:44:59.890673681Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=958 len=1126
[2025-04-30T08:44:59.890685473Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:44:59.890689020Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:44:59.890691484Z TRACE quiche_apps::client] done reading
[2025-04-30T08:44:59.890700221Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=3 
[2025-04-30T08:44:59.890707785Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=4 blocks=[1405802206..1405802207] ecn_counts=None
[2025-04-30T08:44:59.890714527Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=118.234533ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 258104032 }, next_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, 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-30T08:44:59.890751767Z TRACE quiche_apps::client] written 76 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:44:59.890760042Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:44:59.891633842Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:44:59.891642208Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=1151 pn=1405802208 
[2025-04-30T08:44:59.891649271Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=2084 len=1126
[2025-04-30T08:44:59.891660321Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:44:59.891664159Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:44:59.891666543Z TRACE quiche_apps::client] done reading
[2025-04-30T08:44:59.891675219Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=4 
[2025-04-30T08:44:59.891677824Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=4 blocks=[1405802206..1405802208] ecn_counts=None
[2025-04-30T08:44:59.891684276Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=117.264714ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 258104032 }, next_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, 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-30T08:44:59.891725854Z TRACE quiche_apps::client] written 76 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:44:59.891734690Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.009903791Z TRACE quiche_apps::client] timed out
[2025-04-30T08:45:00.009936913Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 loss detection timeout expired
[2025-04-30T08:45:00.009942353Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=237.88086ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 258104032 }, next_time: Instant { tv_sec: 434, tv_nsec: 258104063 }, 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-30T08:45:00.009965476Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.010000241Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=14 pn=5 
[2025-04-30T08:45:00.010003898Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=14794 blocks=[1405802206..1405802208] ecn_counts=None
[2025-04-30T08:45:00.010009208Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm PING mtu_probe=None
[2025-04-30T08:45:00.010026981Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=237.836697ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=78 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 78, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 378100903 }, next_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, max_datagram_size: 1350, last_packet_size: Some(78), 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-30T08:45:00.010120605Z TRACE quiche_apps::client] written 78 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.010130154Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.248425068Z TRACE quiche_apps::client] timed out
[2025-04-30T08:45:00.248456146Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 loss detection timeout expired
[2025-04-30T08:45:00.248475422Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=237.275828ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=78 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 78, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 378100903 }, next_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, max_datagram_size: 1350, last_packet_size: Some(78), 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-30T08:45:00.248498235Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.248532369Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=16 pn=6 
[2025-04-30T08:45:00.248537017Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=44610 blocks=[1405802206..1405802208] ecn_counts=None
[2025-04-30T08:45:00.248543239Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm PING mtu_probe=None
[2025-04-30T08:45:00.248562845Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=475.722125ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=158 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 80, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 616633661 }, next_time: Instant { tv_sec: 434, tv_nsec: 616633661 }, max_datagram_size: 1350, last_packet_size: Some(80), 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-30T08:45:00.248649848Z TRACE quiche_apps::client] written 80 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.248670747Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=16 pn=7 
[2025-04-30T08:45:00.248676507Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=44628 blocks=[1405802206..1405802208] ecn_counts=None
[2025-04-30T08:45:00.248682078Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm PING mtu_probe=None
[2025-04-30T08:45:00.248693439Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=475.741962ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=238 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 160, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 616633661 }, next_time: Instant { tv_sec: 434, tv_nsec: 616633661 }, max_datagram_size: 1350, last_packet_size: Some(80), 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-30T08:45:00.248747760Z TRACE quiche_apps::client] written 80 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.248757949Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.725263608Z TRACE quiche_apps::client] timed out
[2025-04-30T08:45:00.725294305Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 loss detection timeout expired
[2025-04-30T08:45:00.725299845Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=474.912283ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=238 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 160, rate: 425619, last_update: Instant { tv_sec: 434, tv_nsec: 616633661 }, next_time: Instant { tv_sec: 434, tv_nsec: 616633661 }, max_datagram_size: 1350, last_packet_size: Some(80), 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-30T08:45:00.725320293Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.725352483Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=16 pn=8 
[2025-04-30T08:45:00.725356401Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=104213 blocks=[1405802206..1405802208] ecn_counts=None
[2025-04-30T08:45:00.725361631Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm PING mtu_probe=None
[2025-04-30T08:45:00.725377510Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=951.506286ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=318 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 80, rate: 425619, last_update: Instant { tv_sec: 435, tv_nsec: 93454999 }, next_time: Instant { tv_sec: 435, tv_nsec: 93454999 }, max_datagram_size: 1350, last_packet_size: Some(80), 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-30T08:45:00.725470053Z TRACE quiche_apps::client] written 80 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.725487495Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=16 pn=9 
[2025-04-30T08:45:00.725491823Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=104230 blocks=[1405802206..1405802208] ecn_counts=None
[2025-04-30T08:45:00.725496122Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm PING mtu_probe=None
[2025-04-30T08:45:00.725506290Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=951.52506ms latest_rtt=39.648113ms srtt=39.648113ms min_rtt=39.648113ms rttvar=19.824056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=398 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, first_sent_time: Instant { tv_sec: 434, tv_nsec: 378100903 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8398, is_app_limited: true, interval: 39.648113ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 218235759 }), send_elapsed: 0ns, ack_elapsed: 39.648113ms, rtt: 39.648113ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 160, rate: 425619, last_update: Instant { tv_sec: 435, tv_nsec: 93454999 }, next_time: Instant { tv_sec: 435, tv_nsec: 93454999 }, max_datagram_size: 1350, last_packet_size: Some(80), 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-30T08:45:00.725543069Z TRACE quiche_apps::client] written 80 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.725551435Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.757833592Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.757865180Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=1151 pn=1405802209 
[2025-04-30T08:45:00.757901318Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm ACK delay=0 blocks=[8..9] ecn_counts=None
[2025-04-30T08:45:00.757909703Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 packet newly acked 8
[2025-04-30T08:45:00.757913401Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 packet newly acked 9
[2025-04-30T08:45:00.757920143Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 packet 5 lost on epoch 1
[2025-04-30T08:45:00.757923409Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 packet 6 lost on epoch 1
[2025-04-30T08:45:00.757926976Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 packet 7 lost on epoch 1
[2025-04-30T08:45:00.757933438Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=3210 len=1124
[2025-04-30T08:45:00.757981297Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.757987118Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.757990384Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.758005973Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=10 
[2025-04-30T08:45:00.758009910Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=18 blocks=[1405802209..1405802209] ecn_counts=None
[2025-04-30T08:45:00.758044545Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=none latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 125984507 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 93454999 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 93454999 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 93454999 }, max_datagram_size: 1350, last_packet_size: None, iv: 524.981µ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-04-30T08:45:00.758114937Z TRACE quiche_apps::client] written 76 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.758125486Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.758713873Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.758725856Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=1151 pn=1405802210 
[2025-04-30T08:45:00.758736606Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=4334 len=1129
[2025-04-30T08:45:00.758750352Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.758754409Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.758756884Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.758767012Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=11 
[2025-04-30T08:45:00.758769838Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=5 blocks=[1405802209..1405802210] ecn_counts=None
[2025-04-30T08:45:00.758777522Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=none latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 125984507 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 9, last_sent_packet: 11, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.758826704Z TRACE quiche_apps::client] written 76 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.758832485Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.759678684Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.759687981Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=1151 pn=1405802211 
[2025-04-30T08:45:00.759695305Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=5463 len=1129
[2025-04-30T08:45:00.759706826Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.759710403Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.759712797Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.759721734Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=12 
[2025-04-30T08:45:00.759724439Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=4 blocks=[1405802209..1405802211] ecn_counts=None
[2025-04-30T08:45:00.759730781Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=none latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 125984507 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 9, last_sent_packet: 12, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.759765466Z TRACE quiche_apps::client] written 76 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.759770365Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.760659875Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.760667309Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=1151 pn=1405802212 
[2025-04-30T08:45:00.760674082Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=6592 len=1129
[2025-04-30T08:45:00.760683810Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.760692316Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.760694740Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.760703306Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=13 
[2025-04-30T08:45:00.760705921Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=4 blocks=[1405802209..1405802212] ecn_counts=None
[2025-04-30T08:45:00.760712163Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=none latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 125984507 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 9, last_sent_packet: 13, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.760745495Z TRACE quiche_apps::client] written 76 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.760750144Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.761645685Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.761653018Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=1151 pn=1405802213 
[2025-04-30T08:45:00.761660292Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=7721 len=1129
[2025-04-30T08:45:00.761671192Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.761674599Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.761676943Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.761685950Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=12 pn=14 
[2025-04-30T08:45:00.761688495Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=4 blocks=[1405802209..1405802213] ecn_counts=None
[2025-04-30T08:45:00.761694566Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=none latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 125984507 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 9, last_sent_packet: 14, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.761732006Z TRACE quiche_apps::client] written 76 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.761737005Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.762616837Z TRACE quiche_apps::client] got 1180 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.762625083Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Handshake version=1 dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 scid=ad55c7cb04a05c59baaac37b922e36d81a55 len=1131 pn=1405802214 
[2025-04-30T08:45:00.762631905Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=8850 len=1109
[2025-04-30T08:45:00.763165171Z TRACE quiche::tls] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 write message lvl=Handshake len=36
[2025-04-30T08:45:00.763171372Z TRACE quiche::tls] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 set write secret lvl=OneRTT
[2025-04-30T08:45:00.763180259Z TRACE quiche::tls] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 set read secret lvl=OneRTT
[2025-04-30T08:45:00.763194415Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 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(4e4999fd5a31e3b06269e0af00f9424d), max_idle_timeout: 30000, stateless_reset_token: Some(80239883032475496643278428780257264264), max_udp_payload_size: 65527, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 0, initial_max_stream_data_bidi_remote: 262144, initial_max_stream_data_uni: 262144, initial_max_streams_bidi: 100, initial_max_streams_uni: 0, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 7, initial_source_connection_id: Some(ad55c7cb04a05c59baaac37b922e36d81a55), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T08:45:00.763217268Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1180 bytes
[2025-04-30T08:45:00.763221786Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.763224201Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.763232767Z DEBUG quiche_apps::common] sending HTTP request "GET /oxhdpplecz\r\n"
[2025-04-30T08:45:00.763295815Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Handshake version=1 dcid=ad55c7cb04a05c59baaac37b922e36d81a55 scid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 len=53 pn=15 
[2025-04-30T08:45:00.763299571Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=83 blocks=[1405802209..1405802214] ecn_counts=None
[2025-04-30T08:45:00.763302878Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm CRYPTO off=0 len=36
[2025-04-30T08:45:00.763310632Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=105.325484ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=117 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.763330289Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10726 out_len=1233 left=1197
[2025-04-30T08:45:00.763337833Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Short dcid=ad55c7cb04a05c59baaac37b922e36d81a55 key_phase=false len=262 pn=16 
[2025-04-30T08:45:00.763340488Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e6, 34, 33, d1, 57, df, 6a, 3b, 98, 33, e9, c8, a9, 0f, a4, 42, bf, ed, 4b, ca] reset_token=[6a, e8, d2, 76, b1, 37, ee, 1f, aa, 6e, f4, d8, 57, a7, 94, b1]
[2025-04-30T08:45:00.763347220Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[7a, d3, 0f, c4, 01, f5, f7, 12, a1, 67, be, aa, 81, 98, b8, ee, bc, 60, f2, 39] reset_token=[67, e9, 72, 09, c2, 57, b0, bb, ef, fc, 9f, a7, b0, 0e, 8b, a7]
[2025-04-30T08:45:00.763352741Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[42, 6d, ff, 0c, 82, 32, e8, 55, 4c, 1e, c4, 38, ea, 39, e7, 40, 1b, 1e, be, ac] reset_token=[d5, 07, 0e, 29, ec, f9, 97, 53, 49, b9, f0, 79, 97, 07, ce, b6]
[2025-04-30T08:45:00.763358191Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[b2, 65, cc, 36, 17, 72, b7, 26, 82, fd, 45, db, 8e, 1f, 37, 7a, 4b, 31, a9, 19] reset_token=[07, fc, ca, db, 57, 64, dc, 68, bd, 32, 55, 5d, e6, 3d, 6d, a8]
[2025-04-30T08:45:00.763363551Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[ae, 38, 40, a3, a2, ae, 0a, 80, ee, d4, ab, e4, 2c, bd, ba, 3a, 4e, ff, a5, 71] reset_token=[63, f9, b3, a6, 56, ed, bf, 07, cb, a1, 44, 48, 17, 67, 78, 08]
[2025-04-30T08:45:00.763368941Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[3e, 18, 30, 76, 3f, d8, a7, b1, 9b, 8f, 2e, 90, 22, 39, cd, 02, f0, 4c, 5f, 72] reset_token=[7a, b9, 95, d3, e1, 7a, 58, d4, 30, b6, dd, 93, 79, 19, 93, 0b]
[2025-04-30T08:45:00.763374161Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T08:45:00.763382807Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=105.253389ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=415 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.763425046Z TRACE quiche_apps::client] written 415 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.763429965Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.763597778Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.763602777Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Short dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 key_phase=false len=1179 pn=1405802206 
[2025-04-30T08:45:00.763610852Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[38, 30, 89, 8d, 9a, e1, 33, a5, b6, 7d, 4f, e4, b6, 39, 6a, 05, a0, b1] reset_token=[6f, 56, 79, b4, 07, a2, 0f, ad, c8, 0a, fc, 69, 13, 08, c1, a6]
[2025-04-30T08:45:00.763618186Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[2f, 94, a2, 2f, d3, d4, 1d, 22, 78, 81, 26, ed, bd, bf, 8f, f5, cc, 6d] reset_token=[fd, 9e, 78, b7, 10, 51, 39, 44, 44, 8d, 9b, bb, 5d, c4, e6, 86]
[2025-04-30T08:45:00.763623987Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[ea, 3e, a3, 8b, f0, 3e, 0e, 3f, b1, 76, 02, 8c, d7, dc, be, 6f, cd, e5] reset_token=[d9, 0b, 79, 35, ad, f4, 7d, 96, 59, e7, a0, 6f, b2, 15, 77, d9]
[2025-04-30T08:45:00.763629828Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 1c, 01, ef, a8, 6b, 22, cc, 70, 9c, b7, 28, 70, d0, 9e, 52, 86, b8] reset_token=[c2, f7, 35, d1, e7, 37, 9b, 5b, 49, ec, 0d, 80, 0a, e4, 1f, 05]
[2025-04-30T08:45:00.763635779Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[15, 1b, 78, 06, 7d, e8, 7f, a2, 2d, d3, a5, 1f, 9d, de, 50, e6, 03, 67] reset_token=[e0, 8f, 4b, 67, d0, ac, f7, cb, 48, 66, b2, e0, e1, 63, c5, 28]
[2025-04-30T08:45:00.763641129Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[ab, 07, ce, 92, f7, 73, d7, cc, 29, ef, 77, fe, 36, 20, d6, be, ba, 35] reset_token=[b4, da, ae, 3d, 34, f1, 56, f8, ce, 19, a2, 30, 3f, 48, f0, e6]
[2025-04-30T08:45:00.763646619Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[91, af, ca, 0f, b6, 63, a8, 13, a3, e0, 8e, 3f, 5b, b9, db, 4e, f5, 39] reset_token=[2b, fa, c9, fd, 8c, e5, 22, 77, 89, ff, f3, bc, 0a, 58, 0b, 5e]
[2025-04-30T08:45:00.763654033Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm PADDING len=893
[2025-04-30T08:45:00.763674622Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.763678369Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.763680743Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.763687606Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10130 out_len=1350 left=1302
[2025-04-30T08:45:00.763690701Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Short dcid=ad55c7cb04a05c59baaac37b922e36d81a55 key_phase=false len=12 pn=17 
[2025-04-30T08:45:00.763693136Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=10 blocks=[1405802206..1405802206] ecn_counts=None
[2025-04-30T08:45:00.763699648Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=104.936478ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=415 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 15, last_sent_packet: 17, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.763737369Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.763742108Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.796168233Z TRACE quiche_apps::client] got 1342 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.796199181Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Short dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 key_phase=false len=1321 pn=1405802207 
[2025-04-30T08:45:00.796214269Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm PING mtu_probe=None
[2025-04-30T08:45:00.796224418Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm PADDING len=1303
[2025-04-30T08:45:00.796240007Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1342 bytes
[2025-04-30T08:45:00.796245066Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.796248382Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.796261948Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10130 out_len=1350 left=1302
[2025-04-30T08:45:00.796268440Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Short dcid=ad55c7cb04a05c59baaac37b922e36d81a55 key_phase=false len=12 pn=18 
[2025-04-30T08:45:00.796271636Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=8 blocks=[1405802206..1405802207] ecn_counts=None
[2025-04-30T08:45:00.796284260Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=72.351466ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=415 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 15, last_sent_packet: 18, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.796346606Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.796362135Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.797087178Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.797095814Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Short dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 key_phase=false len=1179 pn=1405802208 
[2025-04-30T08:45:00.797102567Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm HANDSHAKE_DONE
[2025-04-30T08:45:00.797110211Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 dropped epoch 1 state
[2025-04-30T08:45:00.797116062Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm NEW_TOKEN len=41
[2025-04-30T08:45:00.797119739Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm CRYPTO off=0 len=237
[2025-04-30T08:45:00.797151157Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm STREAM id=0 off=0 len=873 fin=false
[2025-04-30T08:45:00.797164062Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.797168570Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.797172267Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.797177657Z TRACE quiche_apps::common] received 873 bytes
[2025-04-30T08:45:00.797180282Z TRACE quiche_apps::common] stream 0 has 873 bytes (fin? false)
[2025-04-30T08:45:00.797186984Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10247 out_len=1350 left=1302
[2025-04-30T08:45:00.797190270Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Short dcid=ad55c7cb04a05c59baaac37b922e36d81a55 key_phase=false len=12 pn=19 
[2025-04-30T08:45:00.797192745Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=11 blocks=[1405802206..1405802208] ecn_counts=None
[2025-04-30T08:45:00.797199107Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=96.437019ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=298 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 15, last_sent_packet: 19, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.797237028Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.797241516Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.798057879Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.798064933Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Short dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 key_phase=false len=1179 pn=1405802209 
[2025-04-30T08:45:00.798070954Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm STREAM id=0 off=873 len=1156 fin=false
[2025-04-30T08:45:00.798077606Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.798081073Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.798088637Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.798092985Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:45:00.798095340Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:45:00.798101761Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10247 out_len=1350 left=1302
[2025-04-30T08:45:00.798104877Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Short dcid=ad55c7cb04a05c59baaac37b922e36d81a55 key_phase=false len=12 pn=20 
[2025-04-30T08:45:00.798107252Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=4 blocks=[1405802206..1405802209] ecn_counts=None
[2025-04-30T08:45:00.798114115Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=95.522072ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=298 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 15, last_sent_packet: 20, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.798151394Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.798155842Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.799041114Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.799048007Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Short dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 key_phase=false len=1179 pn=1405802210 
[2025-04-30T08:45:00.799053929Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm STREAM id=0 off=2029 len=1156 fin=false
[2025-04-30T08:45:00.799060190Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.799063587Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.799065881Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.799069588Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:45:00.799071812Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:45:00.799077903Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10247 out_len=1350 left=1302
[2025-04-30T08:45:00.799080879Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Short dcid=ad55c7cb04a05c59baaac37b922e36d81a55 key_phase=false len=12 pn=21 
[2025-04-30T08:45:00.799083243Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=3 blocks=[1405802206..1405802210] ecn_counts=None
[2025-04-30T08:45:00.799088783Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=94.547393ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=298 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 15, last_sent_packet: 21, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.799124921Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.799129089Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.800023989Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.800030952Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Short dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 key_phase=false len=1179 pn=1405802211 
[2025-04-30T08:45:00.800036853Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm STREAM id=0 off=3185 len=1156 fin=false
[2025-04-30T08:45:00.800043175Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.800046601Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.800048935Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.800052562Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:45:00.800054756Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:45:00.800060828Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10247 out_len=1350 left=1302
[2025-04-30T08:45:00.800063893Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Short dcid=ad55c7cb04a05c59baaac37b922e36d81a55 key_phase=false len=12 pn=22 
[2025-04-30T08:45:00.800066258Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm ACK delay=3 blocks=[1405802206..1405802211] ecn_counts=None
[2025-04-30T08:45:00.800072049Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=93.564138ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=298 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 15, last_sent_packet: 22, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.800108687Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.800112975Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.801007745Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.801014658Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx pkt Short dcid=f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 key_phase=false len=1179 pn=1405802212 
[2025-04-30T08:45:00.801020368Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm STREAM id=0 off=4341 len=779 fin=true
[2025-04-30T08:45:00.801024957Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 rx frm PADDING len=377
[2025-04-30T08:45:00.801030998Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 1200 bytes
[2025-04-30T08:45:00.801034414Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.801036789Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.801040506Z TRACE quiche_apps::common] received 779 bytes
[2025-04-30T08:45:00.801042730Z TRACE quiche_apps::common] stream 0 has 779 bytes (fin? true)
[2025-04-30T08:45:00.801045014Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T08:45:00.801047429Z INFO  quiche_apps::common] 1/1 response(s) received in 950.78224ms, closing...
[2025-04-30T08:45:00.801053630Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10247 out_len=1350 left=1314
[2025-04-30T08:45:00.801057027Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx pkt Short dcid=ad55c7cb04a05c59baaac37b922e36d81a55 key_phase=false len=10 pn=23 
[2025-04-30T08:45:00.801059341Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T08:45:00.801065863Z TRACE quiche::recovery::congestion::recovery] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 timer=130.3342ms latest_rtt=32.529508ms srtt=38.758286ms min_rtt=32.529508ms rttvar=16.647693ms cwnd=9450 ssthresh=9450 bytes_in_flight=344 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 435, tv_nsec: 163794750 }) Rate { delivered: 493, delivered_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, first_sent_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, end_of_app_limited: 22, last_sent_packet: 23, largest_acked: 9, rate_sample: RateSample { delivery_rate: 213, is_app_limited: true, interval: 747.883604ms, delivered: 160, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 434, tv_nsec: 378100903 }), send_elapsed: 715.354096ms, ack_elapsed: 747.883604ms, rtt: 32.529508ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 304773, last_update: Instant { tv_sec: 435, tv_nsec: 126120952 }, next_time: Instant { tv_sec: 435, tv_nsec: 127410980 }, 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-04-30T08:45:00.801098173Z TRACE quiche_apps::client] written 46 bytes from 0.0.0.0:51958 to 193.167.100.100:443
[2025-04-30T08:45:00.801101860Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:00.819584113Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:51958
[2025-04-30T08:45:00.819600674Z TRACE quiche_apps::client] 0.0.0.0:51958: processed 44 bytes
[2025-04-30T08:45:00.819604471Z TRACE quiche_apps::client] 0.0.0.0:51958: recv() would block
[2025-04-30T08:45:00.819606986Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:00.819612646Z TRACE quiche_apps::client] 0.0.0.0:51958 -> 193.167.100.100:443: done writing
[2025-04-30T08:45:01.117987563Z TRACE quiche_apps::client] timed out
[2025-04-30T08:45:01.118023820Z TRACE quiche] f578b624ebf8bdab0f0e5ad9405e6930d0ae43c8 draining timeout expired
[2025-04-30T08:45:01.118077240Z TRACE quiche_apps::client] done reading
[2025-04-30T08:45:01.118081909Z INFO  quiche_apps::client] connection closed, recv=17 sent=24 lost=6 retrans=0 sent_bytes=2165 recv_bytes=19322 lost_bytes=238 [local_addr=0.0.0.0:51958 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=17 sent=24 lost=6 retrans=0 rtt=38.758286ms min_rtt=Some(32.529508ms) rttvar=16.647693ms cwnd=9450 sent_bytes=2165 recv_bytes=19322 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=213]