[2025-09-12T13:19:38.569450098Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-09-12T13:19:38.569758726Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:53054 with scid 15b34d422889ee24ed45aaa6509f6b09ca0699f9 [2025-09-12T13:19:38.569802498Z TRACE quiche::tls] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 write message lvl=Initial len=266 [2025-09-12T13:19:38.569819690Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Initial version=1 dcid=62edb1facc2808dfed1a44d12b5335f4 scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=270 pn=0 [2025-09-12T13:19:38.569822776Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm CRYPTO off=0 len=266 [2025-09-12T13:19:38.569836963Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=998.932013ms 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: 321, tv_nsec: 771034541 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 771034541 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 321, tv_nsec: 771034541 }, next_time: Instant { tv_sec: 321, tv_nsec: 771034541 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.569893860Z TRACE quiche_apps::client] written 1200 [2025-09-12T13:19:38.608595276Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:38.608611386Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Initial version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace token= len=117 pn=0 [2025-09-12T13:19:38.608622858Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=127 blocks=[0..0] ecn_counts=None [2025-09-12T13:19:38.608627957Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 0 [2025-09-12T13:19:38.608632907Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=0 len=90 [2025-09-12T13:19:38.608720400Z TRACE quiche::tls] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 set write secret lvl=Handshake [2025-09-12T13:19:38.608729958Z TRACE quiche::tls] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 set read secret lvl=Handshake [2025-09-12T13:19:38.608777988Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1060 pn=1 [2025-09-12T13:19:38.608785983Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=0 len=1039 [2025-09-12T13:19:38.608802665Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:38.608807253Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:38.608809748Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:38.608830437Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Initial version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=1 [2025-09-12T13:19:38.608833362Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=27 blocks=[0..0] ecn_counts=None [2025-09-12T13:19:38.608840806Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=116.28076ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 321, tv_nsec: 810063361 }, next_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.608865512Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=2 [2025-09-12T13:19:38.608868328Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=10 blocks=[1..1] ecn_counts=None [2025-09-12T13:19:38.608873297Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=116.248479ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 321, tv_nsec: 810063361 }, next_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.608883536Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 dropped epoch 0 state [2025-09-12T13:19:38.608909795Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:38.608914985Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:38.609606561Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:38.609614356Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1215 pn=2 [2025-09-12T13:19:38.609621289Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=1039 len=1193 [2025-09-12T13:19:38.609633823Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:38.609637399Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:38.609639774Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:38.609647869Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=3 [2025-09-12T13:19:38.609650494Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=4 blocks=[1..2] ecn_counts=None [2025-09-12T13:19:38.609662386Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=115.675575ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 321, tv_nsec: 810063361 }, next_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.609694897Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:38.609699736Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:38.725865831Z TRACE quiche_apps::client] timed out [2025-09-12T13:19:38.725882592Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 loss detection timeout expired [2025-09-12T13:19:38.725887191Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=233.022663ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 321, tv_nsec: 810063361 }, next_time: Instant { tv_sec: 321, tv_nsec: 810063381 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.725909513Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:38.725933298Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=7 pn=4 [2025-09-12T13:19:38.725937475Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=14539 blocks=[1..2] ecn_counts=None [2025-09-12T13:19:38.725942355Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm PING mtu_probe=None [2025-09-12T13:19:38.725957172Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=232.988318ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=61 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 61, rate: 434495, last_update: Instant { tv_sec: 321, tv_nsec: 927182121 }, next_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, max_datagram_size: 1350, last_packet_size: Some(61), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.726015151Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:38.726022414Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:38.959307359Z TRACE quiche_apps::client] timed out [2025-09-12T13:19:38.959326345Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 loss detection timeout expired [2025-09-12T13:19:38.959331244Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=232.642018ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=61 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 61, rate: 434495, last_update: Instant { tv_sec: 321, tv_nsec: 927182121 }, next_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, max_datagram_size: 1350, last_packet_size: Some(61), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.959356581Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:38.959386217Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=9 pn=5 [2025-09-12T13:19:38.959390705Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=43721 blocks=[1..2] ecn_counts=None [2025-09-12T13:19:38.959396326Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm PING mtu_probe=None [2025-09-12T13:19:38.959413238Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=466.009658ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=124 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 160630843 }, next_time: Instant { tv_sec: 322, tv_nsec: 160630843 }, max_datagram_size: 1350, last_packet_size: Some(63), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.959482838Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:38.959502826Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=9 pn=6 [2025-09-12T13:19:38.959508256Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=43736 blocks=[1..2] ecn_counts=None [2025-09-12T13:19:38.959514147Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm PING mtu_probe=None [2025-09-12T13:19:38.959526961Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=466.021801ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 160630843 }, next_time: Instant { tv_sec: 322, tv_nsec: 160630843 }, max_datagram_size: 1350, last_packet_size: Some(63), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:38.959570553Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:38.959580932Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.426079258Z TRACE quiche_apps::client] timed out [2025-09-12T13:19:39.426109104Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 loss detection timeout expired [2025-09-12T13:19:39.426115015Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=465.490113ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 160630843 }, next_time: Instant { tv_sec: 322, tv_nsec: 160630843 }, max_datagram_size: 1350, last_packet_size: Some(63), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.426140903Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.426186880Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=9 pn=7 [2025-09-12T13:19:39.426191618Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=102071 blocks=[1..2] ecn_counts=None [2025-09-12T13:19:39.426197800Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm PING mtu_probe=None [2025-09-12T13:19:39.426215443Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=932.062548ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=250 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 627428510 }, next_time: Instant { tv_sec: 322, tv_nsec: 627428510 }, max_datagram_size: 1350, last_packet_size: Some(63), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.426279594Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.426298769Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=9 pn=8 [2025-09-12T13:19:39.426304530Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=102085 blocks=[1..2] ecn_counts=None [2025-09-12T13:19:39.426310982Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm PING mtu_probe=None [2025-09-12T13:19:39.426321101Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=932.081624ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 627428510 }, next_time: Instant { tv_sec: 322, tv_nsec: 627428510 }, max_datagram_size: 1350, last_packet_size: Some(63), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.426358812Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.426368630Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.458133255Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.458146169Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1215 pn=3 [2025-09-12T13:19:39.458161899Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=2232 len=1193 [2025-09-12T13:19:39.458180163Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:39.458184511Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.458187016Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.458195802Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=9 [2025-09-12T13:19:39.458198588Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=6 blocks=[1..3] ecn_counts=None [2025-09-12T13:19:39.458206002Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=900.196753ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 7, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 659455607 }, next_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.458243222Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.458251667Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.459147246Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.459155742Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1215 pn=4 [2025-09-12T13:19:39.459162274Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=3425 len=1193 [2025-09-12T13:19:39.459173425Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:39.459177062Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.459179356Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.459187111Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=10 [2025-09-12T13:19:39.459189816Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=3 blocks=[1..4] ecn_counts=None [2025-09-12T13:19:39.459196448Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=899.206347ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 7, last_sent_packet: 10, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 659455607 }, next_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.459234980Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.459243266Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.460171395Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.460178939Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1215 pn=5 [2025-09-12T13:19:39.460185221Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=4618 len=1193 [2025-09-12T13:19:39.460195981Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:39.460199428Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.460201722Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.460209176Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=11 [2025-09-12T13:19:39.460211731Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=3 blocks=[1..5] ecn_counts=None [2025-09-12T13:19:39.460241236Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=898.161449ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 7, last_sent_packet: 11, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 659455607 }, next_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.460274348Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.460282824Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.461195745Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.461203219Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1215 pn=6 [2025-09-12T13:19:39.461209301Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=5811 len=1193 [2025-09-12T13:19:39.461219630Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:39.461222996Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.461225300Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.461237674Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=12 [2025-09-12T13:19:39.461240379Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=4 blocks=[1..6] ecn_counts=None [2025-09-12T13:19:39.461246751Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=897.156105ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 7, last_sent_packet: 12, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 659455607 }, next_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.461278610Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.461286545Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.462222419Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.462229713Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1215 pn=7 [2025-09-12T13:19:39.462235634Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=7004 len=1193 [2025-09-12T13:19:39.462246324Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:39.462249831Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.462252125Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.462259218Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=13 [2025-09-12T13:19:39.462261783Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=3 blocks=[1..7] ecn_counts=None [2025-09-12T13:19:39.462267754Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=896.135101ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 7, last_sent_packet: 13, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 659455607 }, next_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.462303902Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.462312067Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.463247380Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.463254704Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1215 pn=8 [2025-09-12T13:19:39.463260685Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=8197 len=1193 [2025-09-12T13:19:39.463270213Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:39.463273599Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.463275964Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.463283177Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=5 pn=14 [2025-09-12T13:19:39.463285732Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=3 blocks=[1..8] ecn_counts=None [2025-09-12T13:19:39.463291954Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=895.110871ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 7, last_sent_packet: 14, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 659455607 }, next_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.463322662Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.463330116Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.463772024Z TRACE quiche_apps::client] got 627 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.463776843Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=590 pn=9 [2025-09-12T13:19:39.463782483Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=9390 len=568 [2025-09-12T13:19:39.464282931Z TRACE quiche::tls] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 write message lvl=Handshake len=36 [2025-09-12T13:19:39.464287359Z TRACE quiche::tls] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 set write secret lvl=OneRTT [2025-09-12T13:19:39.464296707Z TRACE quiche::tls] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 set read secret lvl=OneRTT [2025-09-12T13:19:39.464310443Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(62edb1facc2808dfed1a44d12b5335f4), max_idle_timeout: 30000, stateless_reset_token: Some(144707673836192991645782596852867136529), max_udp_payload_size: 65527, initial_max_data: 1572864, initial_max_stream_data_bidi_local: 0, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 12288, initial_max_streams_bidi: 100, initial_max_streams_uni: 3, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(837d288357265ace), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-09-12T13:19:39.464335680Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 627 bytes [2025-09-12T13:19:39.464339868Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.464342273Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.464361999Z TRACE quiche::h3] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm SETTINGS stream=2 len=18 [2025-09-12T13:19:39.464377729Z TRACE quiche::h3] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm GREASE stream=0 len=0 [2025-09-12T13:19:39.464383500Z TRACE quiche::h3] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm GREASE stream=0 len=18 [2025-09-12T13:19:39.464390984Z TRACE quiche::h3] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm HEADERS stream=0 len=30 fin=true [2025-09-12T13:19:39.464397135Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /jxufcwpepn", "user-agent: quiche"] [2025-09-12T13:19:39.464460875Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=46 pn=15 [2025-09-12T13:19:39.464464782Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=85 blocks=[1..9] ecn_counts=None [2025-09-12T13:19:39.464468058Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm CRYPTO off=0 len=36 [2025-09-12T13:19:39.464476434Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=932.091732ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=413 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 665718545 }, next_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, max_datagram_size: 1350, last_packet_size: None, iv: 230.152µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.464494588Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Short dcid=837d288357265ace key_phase=false len=304 pn=16 [2025-09-12T13:19:39.464497333Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[7d, 78, e1, b3, 5d, ba, 47, 28, 55, af, c2, 45, 66, ab, b9, 26, 9c, a8, 83, 2f] reset_token=[eb, 47, c1, 83, 93, 84, 3c, 58, 00, 29, 09, c2, 9c, 0d, 3d, 82] [2025-09-12T13:19:39.464503625Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[5b, 9a, 08, c8, a6, 47, 52, f0, b9, 66, 88, 9a, 01, 4a, 99, c0, 27, c0, 38, c5] reset_token=[d1, 68, 6e, 17, 24, fc, 4d, 04, 77, b0, ec, 52, 65, 86, f0, 27] [2025-09-12T13:19:39.464513563Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[ea, d8, 62, 62, 18, 53, 58, cf, 25, 4d, 52, 3d, 73, 63, 2b, 19, 33, af, b0, 9a] reset_token=[9d, 2b, f6, 44, 98, 67, fd, 9b, a2, ba, 2f, ae, 9f, 2b, 00, b1] [2025-09-12T13:19:39.464518753Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[a8, c0, 19, 36, 7a, 71, 2d, 41, 5a, 95, c5, ad, 4c, 36, 39, db, b1, ee, f8, 0a] reset_token=[c8, aa, a9, 52, 49, 50, 46, c8, df, bf, d4, 29, 62, 47, 19, 9a] [2025-09-12T13:19:39.464523773Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[2d, ec, 7f, 72, f4, 75, 52, 8a, 11, 69, e9, 51, de, 56, b2, aa, ed, b9, 8c, 98] reset_token=[48, e0, 4c, 09, 76, 7d, 55, 31, 15, f1, 98, 6a, 4e, fe, 98, 0e] [2025-09-12T13:19:39.464528772Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[7a, 51, 80, 2a, cd, cf, 07, 4e, d4, 60, 83, dc, db, 28, 38, b5, cb, 07, b7, 0a] reset_token=[00, b6, 1a, 7a, 02, 1d, 14, 8c, db, d5, 6e, c8, ff, 6c, 08, 7c] [2025-09-12T13:19:39.464534172Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[81, c1, 16, 9d, 78, fb, c6, f7, 73, a9, fb, a2, f8, ec, 01, f0, ea, a9, 2a, e6] reset_token=[b5, 97, 2b, 19, b0, 50, ee, 98, 24, 32, 42, 67, 7c, ed, a1, f8] [2025-09-12T13:19:39.464539151Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm STREAM id=2 off=0 len=19 fin=false [2025-09-12T13:19:39.464548008Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=932.020238ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=743 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 330, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 665718545 }, next_time: Instant { tv_sec: 322, tv_nsec: 665948697 }, max_datagram_size: 1350, last_packet_size: Some(330), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.464584346Z TRACE quiche_apps::client] written 430 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.464596238Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Short dcid=837d288357265ace key_phase=false len=6 pn=17 [2025-09-12T13:19:39.464600497Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm STREAM id=6 off=0 len=1 fin=false [2025-09-12T13:19:39.464624471Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=931.943554ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=775 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 665855853 }, next_time: Instant { tv_sec: 322, tv_nsec: 665948697 }, max_datagram_size: 1350, last_packet_size: None, iv: 833.151µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.464658515Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.464669456Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Short dcid=837d288357265ace key_phase=false len=6 pn=18 [2025-09-12T13:19:39.464673734Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm STREAM id=10 off=0 len=1 fin=false [2025-09-12T13:19:39.464682270Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=931.885556ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=807 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 665855853 }, next_time: Instant { tv_sec: 322, tv_nsec: 666781848 }, max_datagram_size: 1350, last_packet_size: Some(32), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.464714781Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.464725170Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Short dcid=837d288357265ace key_phase=false len=73 pn=19 [2025-09-12T13:19:39.464729378Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T13:19:39.464755577Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=931.812088ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=906 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 18, last_sent_packet: 19, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 665984965 }, next_time: Instant { tv_sec: 322, tv_nsec: 666781848 }, max_datagram_size: 1350, last_packet_size: None, iv: 301.499µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.464799650Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.464807294Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.464817954Z TRACE quiche_apps::client] got 1097 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.464824136Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1060 pn=11 [2025-09-12T13:19:39.464833202Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=0 len=1039 [2025-09-12T13:19:39.464842580Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1097 bytes [2025-09-12T13:19:39.464846057Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.464848411Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.464856486Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=7 pn=20 [2025-09-12T13:19:39.464859061Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=4 blocks=[1..9, 11..11] ecn_counts=None [2025-09-12T13:19:39.464865593Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=931.702653ms latest_rtt=38.838123ms srtt=38.838123ms min_rtt=38.838123ms rttvar=19.419061ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=906 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 927182121 }, end_of_app_limited: 18, last_sent_packet: 20, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 38.838123ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 771034541 }), send_elapsed: 0ns, ack_elapsed: 38.838123ms, rtt: 38.838123ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434495, last_update: Instant { tv_sec: 322, tv_nsec: 665984965 }, next_time: Instant { tv_sec: 322, tv_nsec: 667083347 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T13:19:39.464895499Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.464902082Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.465701961Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.465706770Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=1215 pn=12 [2025-09-12T13:19:39.465712791Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=1039 len=1193 [2025-09-12T13:19:39.465720786Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 1252 bytes [2025-09-12T13:19:39.465724864Z TRACE quiche_apps::client] got 59 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.465727769Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=22 pn=13 [2025-09-12T13:19:39.465731947Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=13 blocks=[8..8] ecn_counts=None [2025-09-12T13:19:39.465753507Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 8 [2025-09-12T13:19:39.465762715Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet 4 lost on epoch 1 [2025-09-12T13:19:39.465765730Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet 5 lost on epoch 1 [2025-09-12T13:19:39.465773084Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet 6 lost on epoch 1 [2025-09-12T13:19:39.465791959Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 59 bytes [2025-09-12T13:19:39.465795847Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.465798141Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.465806797Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=7 pn=21 [2025-09-12T13:19:39.465809422Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=9 blocks=[3..9, 11..13] ecn_counts=None [2025-09-12T13:19:39.465815794Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=96.432263ms latest_rtt=39.563843ms srtt=38.915837ms min_rtt=38.838123ms rttvar=14.719725ms cwnd=9450 ssthresh=9450 bytes_in_flight=656 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 322, tv_nsec: 666992353 }) Rate { delivered: 396, delivered_time: Instant { tv_sec: 322, tv_nsec: 666992353 }, first_sent_time: Instant { tv_sec: 322, tv_nsec: 627428510 }, end_of_app_limited: 20, last_sent_packet: 21, largest_acked: 8, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 739.810232ms, delivered: 63, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 927182121 }), send_elapsed: 700.246389ms, ack_elapsed: 739.810232ms, rtt: 39.563843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 303539, last_update: Instant { tv_sec: 322, tv_nsec: 665984965 }, next_time: Instant { tv_sec: 322, tv_nsec: 667083347 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=1.9574338205844317 w_max=13500 } [2025-09-12T13:19:39.465849888Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.465856811Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.488756210Z TRACE quiche_apps::client] got 59 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.488770376Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=22 pn=14 [2025-09-12T13:19:39.488779734Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=5 blocks=[8..9] ecn_counts=None [2025-09-12T13:19:39.488783871Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 9 [2025-09-12T13:19:39.488787237Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet 7 lost on epoch 1 [2025-09-12T13:19:39.488798579Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 59 bytes [2025-09-12T13:19:39.488802456Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.488804881Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.488814078Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Handshake version=1 dcid=837d288357265ace scid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 len=7 pn=22 [2025-09-12T13:19:39.488816903Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=5 blocks=[4..9, 11..14] ecn_counts=None [2025-09-12T13:19:39.488824497Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=73.42348ms latest_rtt=39.563843ms srtt=38.915837ms min_rtt=38.838123ms rttvar=14.719725ms cwnd=9450 ssthresh=9450 bytes_in_flight=593 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 322, tv_nsec: 666992353 }) Rate { delivered: 396, delivered_time: Instant { tv_sec: 322, tv_nsec: 690033497 }, first_sent_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, end_of_app_limited: 21, last_sent_packet: 22, largest_acked: 9, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 762.851376ms, delivered: 63, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 927182121 }), send_elapsed: 732.273486ms, ack_elapsed: 762.851376ms, rtt: 30.57789ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 303539, last_update: Instant { tv_sec: 322, tv_nsec: 665984965 }, next_time: Instant { tv_sec: 322, tv_nsec: 667083347 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=1.9574338205844317 w_max=13500 } [2025-09-12T13:19:39.488866456Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.488873920Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.489705579Z TRACE quiche_apps::client] got 59 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.489711349Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=22 pn=15 [2025-09-12T13:19:39.489716760Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=4 blocks=[8..10] ecn_counts=None [2025-09-12T13:19:39.489720286Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 10 [2025-09-12T13:19:39.489729353Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 59 bytes [2025-09-12T13:19:39.489732840Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.489735194Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.489764569Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.490724499Z TRACE quiche_apps::client] got 59 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.490733245Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=22 pn=16 [2025-09-12T13:19:39.490761117Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=3 blocks=[8..11] ecn_counts=None [2025-09-12T13:19:39.490765075Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 11 [2025-09-12T13:19:39.490775354Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 59 bytes [2025-09-12T13:19:39.490779081Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.490781425Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.490786334Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.491715145Z TRACE quiche_apps::client] got 59 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.491719133Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=22 pn=17 [2025-09-12T13:19:39.491723521Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=4 blocks=[8..12] ecn_counts=None [2025-09-12T13:19:39.491726466Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 12 [2025-09-12T13:19:39.491732969Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 59 bytes [2025-09-12T13:19:39.491747786Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.491752375Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.491761552Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.492747300Z TRACE quiche_apps::client] got 59 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.492754323Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=22 pn=18 [2025-09-12T13:19:39.492762388Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=4 blocks=[8..13] ecn_counts=None [2025-09-12T13:19:39.492766987Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 13 [2025-09-12T13:19:39.492778278Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 59 bytes [2025-09-12T13:19:39.492781935Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.492784229Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.492788337Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.493779825Z TRACE quiche_apps::client] got 59 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.493786578Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Handshake version=1 dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 scid=837d288357265ace len=22 pn=19 [2025-09-12T13:19:39.493791347Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=4 blocks=[8..14] ecn_counts=None [2025-09-12T13:19:39.493794412Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 14 [2025-09-12T13:19:39.493801325Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 59 bytes [2025-09-12T13:19:39.493804621Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.493806906Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.493810743Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.496466641Z TRACE quiche_apps::client] got 583 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.496472983Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Short dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 key_phase=false len=562 pn=20 [2025-09-12T13:19:39.496478393Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm CRYPTO off=0 len=540 [2025-09-12T13:19:39.496504522Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm HANDSHAKE_DONE [2025-09-12T13:19:39.496509170Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 dropped epoch 1 state [2025-09-12T13:19:39.496515913Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 583 bytes [2025-09-12T13:19:39.496519550Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.496521894Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.496529178Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Short dcid=837d288357265ace key_phase=false len=5 pn=23 [2025-09-12T13:19:39.496531853Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm ACK delay=6 blocks=[20..20] ecn_counts=None [2025-09-12T13:19:39.496538205Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=90.976122ms latest_rtt=39.563843ms srtt=38.915837ms min_rtt=38.838123ms rttvar=14.719725ms cwnd=9450 ssthresh=9450 bytes_in_flight=493 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 322, tv_nsec: 666992353 }) Rate { delivered: 396, delivered_time: Instant { tv_sec: 322, tv_nsec: 695050650 }, first_sent_time: Instant { tv_sec: 322, tv_nsec: 659455607 }, end_of_app_limited: 22, last_sent_packet: 23, largest_acked: 14, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 767.868529ms, delivered: 63, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 927182121 }), send_elapsed: 732.273486ms, ack_elapsed: 767.868529ms, rtt: 35.595043ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 303539, last_update: Instant { tv_sec: 322, tv_nsec: 665984965 }, next_time: Instant { tv_sec: 322, tv_nsec: 667083347 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=1.9574338205844317 w_max=13500 } [2025-09-12T13:19:39.496580745Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.496588309Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.496732289Z TRACE quiche_apps::client] got 314 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.496751855Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 dropped invalid packet [2025-09-12T13:19:39.496759600Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 314 bytes [2025-09-12T13:19:39.496765030Z TRACE quiche_apps::client] got 48 bytes from 193.167.100.100:443 to 0.0.0.0:53054 [2025-09-12T13:19:39.496768567Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx pkt Short dcid=15b34d422889ee24ed45aaa6509f6b09ca0699f9 key_phase=false len=27 pn=23 [2025-09-12T13:19:39.496774097Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm ACK delay=23 blocks=[16..19] ecn_counts=None [2025-09-12T13:19:39.496777493Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 16 [2025-09-12T13:19:39.496780238Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 17 [2025-09-12T13:19:39.496783264Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 18 [2025-09-12T13:19:39.496785579Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 packet newly acked 19 [2025-09-12T13:19:39.496789135Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 rx frm STREAM id=0 off=0 len=0 fin=true [2025-09-12T13:19:39.496802380Z TRACE quiche_apps::client] 0.0.0.0:53054: processed 48 bytes [2025-09-12T13:19:39.496805846Z TRACE quiche_apps::client] 0.0.0.0:53054: recv() would block [2025-09-12T13:19:39.496808121Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.496810635Z TRACE quiche::h3] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 stream id 0 is readable [2025-09-12T13:19:39.496815735Z TRACE quiche::h3::stream] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 read 0 bytes on stream 0 [2025-09-12T13:19:39.496819382Z DEBUG quiche_apps::common] 1/1 responses received [2025-09-12T13:19:39.496822037Z INFO quiche_apps::common] 1/1 response(s) received in 926.92452ms, closing... [2025-09-12T13:19:39.496829060Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx pkt Short dcid=837d288357265ace key_phase=false len=11 pn=24 [2025-09-12T13:19:39.496831645Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-09-12T13:19:39.496838307Z TRACE quiche::recovery::congestion::recovery] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 timer=114.769649ms latest_rtt=31.250701ms srtt=37.957694ms min_rtt=31.250701ms rttvar=12.956077ms cwnd=9450 ssthresh=9450 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 322, tv_nsec: 699098587 }) Rate { delivered: 889, delivered_time: Instant { tv_sec: 322, tv_nsec: 667083347 }, first_sent_time: Instant { tv_sec: 322, tv_nsec: 667083347 }, end_of_app_limited: 23, last_sent_packet: 24, largest_acked: 19, rate_sample: RateSample { bandwidth: 68.59 Kbps, is_app_limited: true, interval: 770.850428ms, delivered: 556, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 927182121 }), send_elapsed: 739.599727ms, ack_elapsed: 770.850428ms, rtt: 31.250701ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 311201, last_update: Instant { tv_sec: 322, tv_nsec: 665984965 }, next_time: Instant { tv_sec: 322, tv_nsec: 667083347 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=1.9574338205844317 w_max=13500 } [2025-09-12T13:19:39.496877681Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:53054 to 193.167.100.100:443 [2025-09-12T13:19:39.496884063Z TRACE quiche_apps::client] 0.0.0.0:53054 -> 193.167.100.100:443: done writing [2025-09-12T13:19:39.767205900Z TRACE quiche_apps::client] timed out [2025-09-12T13:19:39.767229524Z TRACE quiche] 15b34d422889ee24ed45aaa6509f6b09ca0699f9 draining timeout expired [2025-09-12T13:19:39.767269228Z TRACE quiche_apps::client] done reading [2025-09-12T13:19:39.767276412Z INFO quiche_apps::client] connection closed, recv=21 sent=25 lost=6 retrans=0 sent_bytes=2022 recv_bytes=14036 lost_bytes=250 [local_addr=0.0.0.0:53054 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=21 sent=25 lost=6 retrans=0 rtt=37.957694ms min_rtt=Some(31.250701ms) rttvar=12.956077ms cwnd=9450 sent_bytes=2022 recv_bytes=14036 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=8574]