[2025-04-30T10:30:01.035556994Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T10:30:01.035834742Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:51249 with scid 7feaa0ef24feb4a8a6bebb2cada6148255a0b000
[2025-04-30T10:30:01.035882982Z TRACE quiche::tls] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 write message lvl=Initial len=266
[2025-04-30T10:30:01.035901377Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Initial version=1 dcid=ca42caf8f3720d741992db39e9a0da34 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=270 pn=0 
[2025-04-30T10:30:01.035904453Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm CRYPTO off=0 len=266
[2025-04-30T10:30:01.035918428Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=998.930841ms 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: 423, tv_nsec: 166893588 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 166893588 }, 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: 423, tv_nsec: 166893588 }, next_time: Instant { tv_sec: 423, tv_nsec: 166893588 }, 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-30T10:30:01.036028313Z TRACE quiche_apps::client] written 1200
[2025-04-30T10:30:01.078221327Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:01.078240212Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Initial version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 token= len=116 pn=0 
[2025-04-30T10:30:01.078252825Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T10:30:01.078258777Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 0
[2025-04-30T10:30:01.078264387Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=0 len=90
[2025-04-30T10:30:01.078355838Z TRACE quiche::tls] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 set write secret lvl=Handshake
[2025-04-30T10:30:01.078364414Z TRACE quiche::tls] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 set read secret lvl=Handshake
[2025-04-30T10:30:01.078388930Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=1037 pn=0 
[2025-04-30T10:30:01.078394560Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=0 len=1016
[2025-04-30T10:30:01.078409949Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1232 bytes
[2025-04-30T10:30:01.078414347Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:01.078416842Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:01.078437330Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Initial version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=1 
[2025-04-30T10:30:01.078440175Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=24 blocks=[0..0] ecn_counts=None
[2025-04-30T10:30:01.078447228Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=126.951655ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 209449208 }, next_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, 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-30T10:30:01.078471323Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=2 
[2025-04-30T10:30:01.078474149Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=10 blocks=[0..0] ecn_counts=None
[2025-04-30T10:30:01.078479348Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=126.919866ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 209449208 }, next_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, 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-30T10:30:01.078489938Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 dropped epoch 0 state
[2025-04-30T10:30:01.078526546Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:01.078535022Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:01.079168985Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:01.079179645Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=1193 pn=1 
[2025-04-30T10:30:01.079186698Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=1016 len=1171
[2025-04-30T10:30:01.079199432Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1232 bytes
[2025-04-30T10:30:01.079203209Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:01.079205644Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:01.079213969Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=3 
[2025-04-30T10:30:01.079216654Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=4 blocks=[0..1] ecn_counts=None
[2025-04-30T10:30:01.079228677Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=126.364299ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 209449208 }, next_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, 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-30T10:30:01.079266387Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:01.079274231Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:01.080132383Z TRACE quiche_apps::client] got 1136 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:01.080141370Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=1097 pn=2 
[2025-04-30T10:30:01.080148162Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=2187 len=1075
[2025-04-30T10:30:01.080159333Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1136 bytes
[2025-04-30T10:30:01.080162950Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:01.080165374Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:01.080173409Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=4 
[2025-04-30T10:30:01.080175984Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=4 blocks=[0..2] ecn_counts=None
[2025-04-30T10:30:01.080182446Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=125.4106ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 209449208 }, next_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, 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-30T10:30:01.080219165Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:01.080226789Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:01.206408572Z TRACE quiche_apps::client] timed out
[2025-04-30T10:30:01.206424171Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 loss detection timeout expired
[2025-04-30T10:30:01.206429080Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=254.318265ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 209449208 }, next_time: Instant { tv_sec: 423, tv_nsec: 209449238 }, 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-30T10:30:01.206446363Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:01.206467402Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=7 pn=5 
[2025-04-30T10:30:01.206471169Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=15790 blocks=[0..2] ecn_counts=None
[2025-04-30T10:30:01.206475727Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm PING mtu_probe=None
[2025-04-30T10:30:01.206488381Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=254.288089ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=63 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 337496252 }, next_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, 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-04-30T10:30:01.206550637Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:01.206560766Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:01.461868369Z TRACE quiche_apps::client] timed out
[2025-04-30T10:30:01.461890540Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 loss detection timeout expired
[2025-04-30T10:30:01.461895680Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=253.204695ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=63 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 337496252 }, next_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, 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-04-30T10:30:01.461930094Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:01.461979737Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=9 pn=6 
[2025-04-30T10:30:01.461984526Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=47726 blocks=[0..2] ecn_counts=None
[2025-04-30T10:30:01.461989856Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm PING mtu_probe=None
[2025-04-30T10:30:01.462007188Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=508.580224ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=128 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 65, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 592982908 }, next_time: Instant { tv_sec: 423, tv_nsec: 592982908 }, max_datagram_size: 1350, last_packet_size: Some(65), 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-30T10:30:01.462077489Z TRACE quiche_apps::client] written 65 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:01.462096585Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=9 pn=7 
[2025-04-30T10:30:01.462102395Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=47744 blocks=[0..2] ecn_counts=None
[2025-04-30T10:30:01.462107876Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm PING mtu_probe=None
[2025-04-30T10:30:01.462119888Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=508.613276ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=193 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 130, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 592982908 }, next_time: Instant { tv_sec: 423, tv_nsec: 592982908 }, max_datagram_size: 1350, last_packet_size: Some(65), 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-30T10:30:01.462170092Z TRACE quiche_apps::client] written 65 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:01.462180601Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:01.971721131Z TRACE quiche_apps::client] timed out
[2025-04-30T10:30:01.971745887Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 loss detection timeout expired
[2025-04-30T10:30:01.971751748Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=507.629267ms latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=193 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 130, rate: 398113, last_update: Instant { tv_sec: 423, tv_nsec: 592982908 }, next_time: Instant { tv_sec: 423, tv_nsec: 592982908 }, max_datagram_size: 1350, last_packet_size: Some(65), 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-30T10:30:01.971775292Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:01.971807552Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=9 pn=8 
[2025-04-30T10:30:01.971812131Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=111457 blocks=[0..2] ecn_counts=None
[2025-04-30T10:30:01.971817871Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm PING mtu_probe=None
[2025-04-30T10:30:01.971835454Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=1.017246109s latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=258 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 65, rate: 398113, last_update: Instant { tv_sec: 424, tv_nsec: 102828377 }, next_time: Instant { tv_sec: 424, tv_nsec: 102828377 }, max_datagram_size: 1350, last_packet_size: Some(65), 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-30T10:30:01.971917057Z TRACE quiche_apps::client] written 65 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:01.971930131Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=9 pn=9 
[2025-04-30T10:30:01.971933587Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=111473 blocks=[0..2] ecn_counts=None
[2025-04-30T10:30:01.971937184Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm PING mtu_probe=None
[2025-04-30T10:30:01.971944448Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=1.017275413s latest_rtt=42.387386ms srtt=42.387386ms min_rtt=42.387386ms rttvar=21.193693ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=323 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, first_sent_time: Instant { tv_sec: 423, tv_nsec: 337496252 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7856, is_app_limited: true, interval: 42.387386ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 166893588 }), send_elapsed: 0ns, ack_elapsed: 42.387386ms, rtt: 42.387386ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 130, rate: 398113, last_update: Instant { tv_sec: 424, tv_nsec: 102828377 }, next_time: Instant { tv_sec: 424, tv_nsec: 102828377 }, max_datagram_size: 1350, last_packet_size: Some(65), 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-30T10:30:01.972007255Z TRACE quiche_apps::client] written 65 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:01.972014709Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.003684578Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.003702942Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=1193 pn=3 
[2025-04-30T10:30:02.003714965Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm ACK delay=0 blocks=[8..8] ecn_counts=None
[2025-04-30T10:30:02.003720755Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 8
[2025-04-30T10:30:02.003726967Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet 5 lost on epoch 1
[2025-04-30T10:30:02.003730434Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet 6 lost on epoch 1
[2025-04-30T10:30:02.003733479Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet 7 lost on epoch 1
[2025-04-30T10:30:02.003746394Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=3262 len=1166
[2025-04-30T10:30:02.003769617Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1232 bytes
[2025-04-30T10:30:02.003775147Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.003778333Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.003789795Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=10 
[2025-04-30T10:30:02.003793431Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=11 blocks=[3..3] ecn_counts=None
[2025-04-30T10:30:02.003809902Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=83.244698ms latest_rtt=31.914606ms srtt=41.078287ms min_rtt=31.914606ms rttvar=18.513464ms cwnd=9450 ssthresh=9450 bytes_in_flight=65 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 134742983 }) Rate { delivered: 398, delivered_time: Instant { tv_sec: 424, tv_nsec: 134742983 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 102828377 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { delivery_rate: 81, is_app_limited: true, interval: 797.246731ms, delivered: 65, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 337496252 }), send_elapsed: 765.332125ms, ack_elapsed: 797.246731ms, rtt: 31.914606ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 287560, last_update: Instant { tv_sec: 424, tv_nsec: 134826359 }, next_time: Instant { tv_sec: 424, tv_nsec: 102828377 }, max_datagram_size: 1350, last_packet_size: None, iv: 452.08µ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-30T10:30:02.003862009Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.003868301Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.004673484Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.004683302Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=1193 pn=4 
[2025-04-30T10:30:02.004691407Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=4428 len=1171
[2025-04-30T10:30:02.004730981Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1232 bytes
[2025-04-30T10:30:02.004735479Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.004737984Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.004747612Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=11 
[2025-04-30T10:30:02.004750597Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=8 blocks=[3..4] ecn_counts=None
[2025-04-30T10:30:02.004758703Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=82.296017ms latest_rtt=31.914606ms srtt=41.078287ms min_rtt=31.914606ms rttvar=18.513464ms cwnd=9450 ssthresh=9450 bytes_in_flight=65 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 134742983 }) Rate { delivered: 398, delivered_time: Instant { tv_sec: 424, tv_nsec: 134742983 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 102828377 }, end_of_app_limited: 9, last_sent_packet: 11, largest_acked: 8, rate_sample: RateSample { delivery_rate: 81, is_app_limited: true, interval: 797.246731ms, delivered: 65, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 337496252 }), send_elapsed: 765.332125ms, ack_elapsed: 797.246731ms, rtt: 31.914606ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 287560, last_update: Instant { tv_sec: 424, tv_nsec: 134826359 }, next_time: Instant { tv_sec: 424, tv_nsec: 136237298 }, 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-30T10:30:02.004804578Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.004809738Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.005671887Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.005684651Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=1193 pn=5 
[2025-04-30T10:30:02.005693126Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=5599 len=1171
[2025-04-30T10:30:02.005706531Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1232 bytes
[2025-04-30T10:30:02.005711931Z TRACE quiche_apps::client] got 61 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.005715388Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=22 pn=6 
[2025-04-30T10:30:02.005720337Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm ACK delay=0 blocks=[8..9] ecn_counts=None
[2025-04-30T10:30:02.005724114Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 9
[2025-04-30T10:30:02.005732740Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 61 bytes
[2025-04-30T10:30:02.005736237Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.005738601Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.005747929Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=12 
[2025-04-30T10:30:02.005750794Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=3 blocks=[3..6] ecn_counts=None
[2025-04-30T10:30:02.005757166Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=none latest_rtt=33.930027ms srtt=40.184754ms min_rtt=31.914606ms rttvar=15.672163ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 134742983 }) Rate { delivered: 463, delivered_time: Instant { tv_sec: 424, tv_nsec: 136237298 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 136237298 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 9, rate_sample: RateSample { delivery_rate: 163, is_app_limited: true, interval: 797.246731ms, delivered: 130, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 337496252 }), send_elapsed: 765.332125ms, ack_elapsed: 797.246731ms, rtt: 31.914606ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 293954, last_update: Instant { tv_sec: 424, tv_nsec: 134826359 }, next_time: Instant { tv_sec: 424, tv_nsec: 136237298 }, 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-30T10:30:02.005799685Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.005804764Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.037280131Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.037295269Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=1193 pn=7 
[2025-04-30T10:30:02.037305578Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=6770 len=1171
[2025-04-30T10:30:02.037323512Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1232 bytes
[2025-04-30T10:30:02.037328561Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.037339471Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.037351314Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=13 
[2025-04-30T10:30:02.037354890Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=7 blocks=[3..7] ecn_counts=None
[2025-04-30T10:30:02.037364709Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=none latest_rtt=33.930027ms srtt=40.184754ms min_rtt=31.914606ms rttvar=15.672163ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 134742983 }) Rate { delivered: 463, delivered_time: Instant { tv_sec: 424, tv_nsec: 136237298 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 136237298 }, end_of_app_limited: 11, last_sent_packet: 13, largest_acked: 9, rate_sample: RateSample { delivery_rate: 163, is_app_limited: true, interval: 797.246731ms, delivered: 130, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 337496252 }), send_elapsed: 765.332125ms, ack_elapsed: 797.246731ms, rtt: 31.914606ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 293954, last_update: Instant { tv_sec: 424, tv_nsec: 134826359 }, next_time: Instant { tv_sec: 424, tv_nsec: 136237298 }, 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-30T10:30:02.037413830Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.037419792Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.068524286Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.068541237Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=1193 pn=8 
[2025-04-30T10:30:02.068551116Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=7941 len=1171
[2025-04-30T10:30:02.068568759Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1232 bytes
[2025-04-30T10:30:02.068573097Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.068575742Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.068586753Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=5 pn=14 
[2025-04-30T10:30:02.068589758Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=5 blocks=[3..8] ecn_counts=None
[2025-04-30T10:30:02.068598284Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=none latest_rtt=33.930027ms srtt=40.184754ms min_rtt=31.914606ms rttvar=15.672163ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 134742983 }) Rate { delivered: 463, delivered_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, end_of_app_limited: 11, last_sent_packet: 14, largest_acked: 9, rate_sample: RateSample { delivery_rate: 163, is_app_limited: true, interval: 797.246731ms, delivered: 130, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 337496252 }), send_elapsed: 765.332125ms, ack_elapsed: 797.246731ms, rtt: 31.914606ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 293954, last_update: Instant { tv_sec: 424, tv_nsec: 199623507 }, next_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, 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-30T10:30:02.068650411Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.068656202Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.096999424Z TRACE quiche_apps::client] got 924 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.097018900Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=885 pn=9 
[2025-04-30T10:30:02.097029700Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=9112 len=863
[2025-04-30T10:30:02.097625202Z TRACE quiche::tls] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 write message lvl=Handshake len=36
[2025-04-30T10:30:02.097630752Z TRACE quiche::tls] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 set write secret lvl=OneRTT
[2025-04-30T10:30:02.097641412Z TRACE quiche::tls] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 set read secret lvl=OneRTT
[2025-04-30T10:30:02.097658664Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 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(ca42caf8f3720d741992db39e9a0da34), max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 4611686018427387903, initial_max_stream_data_bidi_local: 1048576, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 1048576, initial_max_streams_bidi: 16, initial_max_streams_uni: 16, ack_delay_exponent: 3, max_ack_delay: 20, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(330dd96550599e24a7f6), retry_source_connection_id: None, max_datagram_frame_size: Some(0), unknown_params: None }
[2025-04-30T10:30:02.097686446Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 924 bytes
[2025-04-30T10:30:02.097691926Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.097695172Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.097704740Z DEBUG quiche_apps::common] sending HTTP request "GET /ijffjdvfzb\r\n"
[2025-04-30T10:30:02.097778748Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Handshake version=1 dcid=330dd96550599e24a7f6 scid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 len=46 pn=15 
[2025-04-30T10:30:02.097783878Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=95 blocks=[3..9] ecn_counts=None
[2025-04-30T10:30:02.097788406Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm CRYPTO off=0 len=36
[2025-04-30T10:30:02.097798575Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=102.842838ms latest_rtt=33.930027ms srtt=40.184754ms min_rtt=31.914606ms rttvar=15.672163ms cwnd=9450 ssthresh=9450 bytes_in_flight=102 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 260588604 }) Rate { delivered: 463, delivered_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 9, rate_sample: RateSample { delivery_rate: 163, is_app_limited: true, interval: 797.246731ms, delivered: 130, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 337496252 }), send_elapsed: 765.332125ms, ack_elapsed: 797.246731ms, rtt: 31.914606ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 293954, last_update: Instant { tv_sec: 424, tv_nsec: 199623507 }, next_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, 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-30T10:30:02.097825475Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10726 out_len=1248 left=1220
[2025-04-30T10:30:02.097834582Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Short dcid=330dd96550599e24a7f6 key_phase=false len=302 pn=16 
[2025-04-30T10:30:02.097837939Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ea, a7, 5f, f9, 72, 71, 3e, f3, fb, 86, 29, 52, d8, 15, 0e, b3, 70, 8e, e9, 07] reset_token=[0e, 6f, fa, f7, fe, 6a, e8, 2e, 58, 81, 61, 26, 09, 04, 85, c5]
[2025-04-30T10:30:02.097846444Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[ce, bf, 35, 61, 3f, 05, 2a, 9e, 65, 8d, db, bd, 95, 8d, 15, 47, 7f, 6f, 94, 2a] reset_token=[67, 38, 09, b1, 71, c3, 14, d7, 4b, c5, ca, fe, 17, 2d, 6d, 5b]
[2025-04-30T10:30:02.097853377Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[8f, 4d, 02, f2, 14, 7c, b9, 05, 7f, 46, a5, 0c, 57, d0, 4a, 39, 8e, 0c, b8, dc] reset_token=[31, ee, e2, 9e, 19, 82, 30, e5, 2f, 33, d5, 49, 91, d9, c9, d8]
[2025-04-30T10:30:02.097859920Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[74, 11, 45, 20, 90, e2, c2, 80, 9b, 99, bf, d9, e6, f6, bf, 75, ac, ea, c8, d4] reset_token=[59, 20, a7, f0, ac, 27, 7e, 85, b8, ed, 57, b4, 53, 8a, 3d, 2e]
[2025-04-30T10:30:02.097866141Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[32, 2c, 89, a4, 5b, ee, 8e, e1, c4, f6, 19, 0f, 1d, 71, c1, ba, e6, ed, 24, 82] reset_token=[04, 11, b9, 25, e6, 66, 3d, 9e, 7b, 3c, 89, df, 96, 19, af, a3]
[2025-04-30T10:30:02.097872483Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ab, e7, ec, 85, e1, 52, de, df, 5c, 74, 94, f5, 23, 38, 27, 57, f2, bb, 45, da] reset_token=[f5, 92, 68, 44, 95, 33, 26, 76, 7b, 7d, 01, df, 5b, 3b, 2d, b8]
[2025-04-30T10:30:02.097878724Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[e8, af, 1d, 2b, 3d, 95, 62, 6e, ab, ad, 81, 7e, f3, 9c, 51, b2, ea, 54, 89, 74] reset_token=[01, 0f, f0, 48, 15, e3, 27, c6, ef, a9, ba, 42, 76, 8c, a2, 4b]
[2025-04-30T10:30:02.097884976Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T10:30:02.097896017Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=102.745637ms latest_rtt=33.930027ms srtt=40.184754ms min_rtt=31.914606ms rttvar=15.672163ms cwnd=9450 ssthresh=9450 bytes_in_flight=432 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 260588604 }) Rate { delivered: 463, delivered_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 9, rate_sample: RateSample { delivery_rate: 163, is_app_limited: true, interval: 797.246731ms, delivered: 130, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 423, tv_nsec: 337496252 }), send_elapsed: 765.332125ms, ack_elapsed: 797.246731ms, rtt: 31.914606ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 293954, last_update: Instant { tv_sec: 424, tv_nsec: 199623507 }, next_time: Instant { tv_sec: 424, tv_nsec: 199623507 }, 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-30T10:30:02.097948004Z TRACE quiche_apps::client] written 432 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.097953675Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.130559681Z TRACE quiche_apps::client] got 1332 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.130580881Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Handshake version=1 dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 scid=330dd96550599e24a7f6 len=22 pn=10 
[2025-04-30T10:30:02.130591310Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm ACK delay=0 blocks=[8..15] ecn_counts=None
[2025-04-30T10:30:02.130597011Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 10
[2025-04-30T10:30:02.130599906Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 11
[2025-04-30T10:30:02.130602461Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 12
[2025-04-30T10:30:02.130604685Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 13
[2025-04-30T10:30:02.130607731Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 14
[2025-04-30T10:30:02.130610536Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 15
[2025-04-30T10:30:02.130629221Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Short dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 key_phase=false len=1250 pn=0 
[2025-04-30T10:30:02.130635232Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm ACK delay=0 blocks=[16..16] ecn_counts=None
[2025-04-30T10:30:02.130638288Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 packet newly acked 16
[2025-04-30T10:30:02.130641524Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm HANDSHAKE_DONE
[2025-04-30T10:30:02.130646022Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 dropped epoch 1 state
[2025-04-30T10:30:02.130650260Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[b9, d6, 3b, cc, 7b, 6d, c6, 3f, 0d, d3] reset_token=[95, ee, 9c, d9, 3d, 51, a8, 4f, 87, a6, 88, 34, e1, d5, f8, 49]
[2025-04-30T10:30:02.130657433Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f1, 0d, d4, 89, ee, ca, ba, 45, 4a, bd] reset_token=[22, 96, ba, 65, 4b, 91, 52, 00, 9e, 59, a1, 0a, 58, 50, a7, 73]
[2025-04-30T10:30:02.130662984Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[49, cb, 22, 71, 0e, 8f, 38, d0, 81, 6d] reset_token=[f6, 69, 94, d7, e7, a7, b2, 75, 07, f2, 76, b1, 60, 3a, be, e1]
[2025-04-30T10:30:02.130668043Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[63, c0, b5, 8a, f1, ca, 51, d1, d6, 5d] reset_token=[9a, be, 87, 8c, f5, c1, c4, 3d, c3, 2a, e5, 9c, 6a, 22, 0c, 89]
[2025-04-30T10:30:02.130673153Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[fc, 18, 1e, 87, cc, 68, 39, 8e, 01, 17] reset_token=[f0, 67, ea, 93, 66, 7c, 7d, 7e, d7, b7, 27, 82, b8, 8e, 07, b4]
[2025-04-30T10:30:02.130678272Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[4c, 92, f0, 05, 79, 2c, 0f, 4c, 86, 91] reset_token=[8e, 49, d3, 18, 51, 2a, e8, c2, 32, 00, f8, e7, ef, d7, 85, c0]
[2025-04-30T10:30:02.130690896Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[de, 7d, aa, 50, ec, cd, 80, c7, a5, 55] reset_token=[31, 98, 3b, 6b, ed, df, f4, 70, 78, 2d, 19, 15, 73, 35, 52, 95]
[2025-04-30T10:30:02.130698470Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm PADDING len=1017
[2025-04-30T10:30:02.130723547Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1332 bytes
[2025-04-30T10:30:02.130728456Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.130731001Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.130740418Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=11130 out_len=1350 left=1317
[2025-04-30T10:30:02.130744947Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Short dcid=330dd96550599e24a7f6 key_phase=false len=5 pn=17 
[2025-04-30T10:30:02.130747461Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=13 blocks=[0..0] ecn_counts=None
[2025-04-30T10:30:02.130754625Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=none latest_rtt=62.04879ms srtt=45.303414ms min_rtt=31.914606ms rttvar=17.689682ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 260588604 }) Rate { delivered: 895, delivered_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 16, rate_sample: RateSample { delivery_rate: 3445, is_app_limited: true, interval: 125.382691ms, delivered: 432, prior_delivered: 463, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 136237298 }), send_elapsed: 0ns, ack_elapsed: 125.382691ms, rtt: 125.382691ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 260741, last_update: Instant { tv_sec: 424, tv_nsec: 261779537 }, next_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, 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-30T10:30:02.130801382Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.130806171Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.137584159Z TRACE quiche_apps::client] got 1332 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.137596533Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Short dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 key_phase=false len=1311 pn=1 
[2025-04-30T10:30:02.137604608Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm PING mtu_probe=None
[2025-04-30T10:30:02.137609647Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm STREAM id=0 off=0 len=1291 fin=false
[2025-04-30T10:30:02.137619686Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1332 bytes
[2025-04-30T10:30:02.137624284Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.137627320Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.137633411Z TRACE quiche_apps::common] received 1291 bytes
[2025-04-30T10:30:02.137636687Z TRACE quiche_apps::common] stream 0 has 1291 bytes (fin? false)
[2025-04-30T10:30:02.137645364Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=11130 out_len=1350 left=1317
[2025-04-30T10:30:02.137649592Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Short dcid=330dd96550599e24a7f6 key_phase=false len=5 pn=18 
[2025-04-30T10:30:02.137652627Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=6 blocks=[0..1] ecn_counts=None
[2025-04-30T10:30:02.137667064Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=none latest_rtt=62.04879ms srtt=45.303414ms min_rtt=31.914606ms rttvar=17.689682ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 260588604 }) Rate { delivered: 895, delivered_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, end_of_app_limited: 16, last_sent_packet: 18, largest_acked: 16, rate_sample: RateSample { delivery_rate: 3445, is_app_limited: true, interval: 125.382691ms, delivered: 432, prior_delivered: 463, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 136237298 }), send_elapsed: 0ns, ack_elapsed: 125.382691ms, rtt: 125.382691ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 260741, last_update: Instant { tv_sec: 424, tv_nsec: 261779537 }, next_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, 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-30T10:30:02.137710435Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.137715645Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.159737961Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.159752788Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Short dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 key_phase=false len=1211 pn=2 
[2025-04-30T10:30:02.159762406Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm STREAM id=0 off=1291 len=1190 fin=false
[2025-04-30T10:30:02.159773687Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1232 bytes
[2025-04-30T10:30:02.159778436Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.159781412Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.159786882Z TRACE quiche_apps::common] received 1190 bytes
[2025-04-30T10:30:02.159789958Z TRACE quiche_apps::common] stream 0 has 1190 bytes (fin? false)
[2025-04-30T10:30:02.159799185Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=11130 out_len=1350 left=1317
[2025-04-30T10:30:02.159803603Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Short dcid=330dd96550599e24a7f6 key_phase=false len=5 pn=19 
[2025-04-30T10:30:02.159806709Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=5 blocks=[0..2] ecn_counts=None
[2025-04-30T10:30:02.159814874Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=none latest_rtt=62.04879ms srtt=45.303414ms min_rtt=31.914606ms rttvar=17.689682ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 260588604 }) Rate { delivered: 895, delivered_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, end_of_app_limited: 16, last_sent_packet: 19, largest_acked: 16, rate_sample: RateSample { delivery_rate: 3445, is_app_limited: true, interval: 125.382691ms, delivered: 432, prior_delivered: 463, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 136237298 }), send_elapsed: 0ns, ack_elapsed: 125.382691ms, rtt: 125.382691ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 260741, last_update: Instant { tv_sec: 424, tv_nsec: 261779537 }, next_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, 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-30T10:30:02.159871099Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.159876269Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.179461173Z TRACE quiche_apps::client] got 1372 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.179478685Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Short dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 key_phase=false len=1351 pn=3 
[2025-04-30T10:30:02.179488453Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm PING mtu_probe=None
[2025-04-30T10:30:02.179493653Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm STREAM id=0 off=2481 len=1329 fin=false
[2025-04-30T10:30:02.179506307Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1372 bytes
[2025-04-30T10:30:02.179511226Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.179514322Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.179520213Z TRACE quiche_apps::common] received 1329 bytes
[2025-04-30T10:30:02.179523469Z TRACE quiche_apps::common] stream 0 has 1329 bytes (fin? false)
[2025-04-30T10:30:02.179533337Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=11130 out_len=1350 left=1317
[2025-04-30T10:30:02.179538958Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Short dcid=330dd96550599e24a7f6 key_phase=false len=5 pn=20 
[2025-04-30T10:30:02.179542084Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=6 blocks=[0..3] ecn_counts=None
[2025-04-30T10:30:02.179550800Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=none latest_rtt=62.04879ms srtt=45.303414ms min_rtt=31.914606ms rttvar=17.689682ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 260588604 }) Rate { delivered: 895, delivered_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, end_of_app_limited: 16, last_sent_packet: 20, largest_acked: 16, rate_sample: RateSample { delivery_rate: 3445, is_app_limited: true, interval: 125.382691ms, delivered: 432, prior_delivered: 463, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 136237298 }), send_elapsed: 0ns, ack_elapsed: 125.382691ms, rtt: 125.382691ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 260741, last_update: Instant { tv_sec: 424, tv_nsec: 261779537 }, next_time: Instant { tv_sec: 424, tv_nsec: 261779537 }, 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-30T10:30:02.179600813Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.179606023Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.196585222Z TRACE quiche_apps::client] got 1332 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.196600621Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Short dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 key_phase=false len=1311 pn=4 
[2025-04-30T10:30:02.196610369Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm STREAM id=0 off=3810 len=1290 fin=false
[2025-04-30T10:30:02.196621459Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1332 bytes
[2025-04-30T10:30:02.196632730Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.196635426Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.196640765Z TRACE quiche_apps::common] received 1290 bytes
[2025-04-30T10:30:02.196643130Z TRACE quiche_apps::common] stream 0 has 1290 bytes (fin? false)
[2025-04-30T10:30:02.196652257Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=11130 out_len=1350 left=1317
[2025-04-30T10:30:02.196656254Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Short dcid=330dd96550599e24a7f6 key_phase=false len=5 pn=21 
[2025-04-30T10:30:02.196658749Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm ACK delay=6 blocks=[0..4] ecn_counts=None
[2025-04-30T10:30:02.196667145Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=none latest_rtt=62.04879ms srtt=45.303414ms min_rtt=31.914606ms rttvar=17.689682ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 260588604 }) Rate { delivered: 895, delivered_time: Instant { tv_sec: 424, tv_nsec: 327691266 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 327691266 }, end_of_app_limited: 16, last_sent_packet: 21, largest_acked: 16, rate_sample: RateSample { delivery_rate: 3445, is_app_limited: true, interval: 125.382691ms, delivered: 432, prior_delivered: 463, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 136237298 }), send_elapsed: 0ns, ack_elapsed: 125.382691ms, rtt: 125.382691ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 260741, last_update: Instant { tv_sec: 424, tv_nsec: 327691266 }, next_time: Instant { tv_sec: 424, tv_nsec: 327691266 }, 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-30T10:30:02.196708151Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.196712359Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.211301721Z TRACE quiche_apps::client] got 1422 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.211323391Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx pkt Short dcid=7feaa0ef24feb4a8a6bebb2cada6148255a0b000 key_phase=false len=1401 pn=5 
[2025-04-30T10:30:02.211332718Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm PING mtu_probe=None
[2025-04-30T10:30:02.211336977Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm STREAM id=0 off=5100 len=20 fin=true
[2025-04-30T10:30:02.211342347Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm CRYPTO off=0 len=321
[2025-04-30T10:30:02.211377312Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm NEW_TOKEN len=43
[2025-04-30T10:30:02.211390126Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 rx frm PADDING len=988
[2025-04-30T10:30:02.211407168Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 1422 bytes
[2025-04-30T10:30:02.211415042Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.211418268Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.211424580Z TRACE quiche_apps::common] received 20 bytes
[2025-04-30T10:30:02.211426944Z TRACE quiche_apps::common] stream 0 has 20 bytes (fin? true)
[2025-04-30T10:30:02.211429459Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T10:30:02.211431924Z INFO  quiche_apps::common] 1/1 response(s) received in 1.175395385s, closing...
[2025-04-30T10:30:02.211439728Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=11130 out_len=1350 left=1322
[2025-04-30T10:30:02.211450057Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx pkt Short dcid=330dd96550599e24a7f6 key_phase=false len=10 pn=22 
[2025-04-30T10:30:02.211452742Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T10:30:02.211461258Z TRACE quiche::recovery::congestion::recovery] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 timer=136.037335ms latest_rtt=62.04879ms srtt=45.303414ms min_rtt=31.914606ms rttvar=17.689682ms cwnd=9450 ssthresh=9450 bytes_in_flight=38 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 424, tv_nsec: 374256888 }) Rate { delivered: 895, delivered_time: Instant { tv_sec: 424, tv_nsec: 327691266 }, first_sent_time: Instant { tv_sec: 424, tv_nsec: 327691266 }, end_of_app_limited: 21, last_sent_packet: 22, largest_acked: 16, rate_sample: RateSample { delivery_rate: 3445, is_app_limited: true, interval: 125.382691ms, delivered: 432, prior_delivered: 463, prior_time: Some(Instant { tv_sec: 424, tv_nsec: 136237298 }), send_elapsed: 0ns, ack_elapsed: 125.382691ms, rtt: 125.382691ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 260741, last_update: Instant { tv_sec: 424, tv_nsec: 327691266 }, next_time: Instant { tv_sec: 424, tv_nsec: 327691266 }, 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-30T10:30:02.211513937Z TRACE quiche_apps::client] written 38 bytes from 0.0.0.0:51249 to 193.167.100.100:443
[2025-04-30T10:30:02.211518826Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.242036649Z TRACE quiche_apps::client] got 47 bytes from 193.167.100.100:443 to 0.0.0.0:51249
[2025-04-30T10:30:02.242048100Z TRACE quiche_apps::client] 0.0.0.0:51249: processed 47 bytes
[2025-04-30T10:30:02.242052559Z TRACE quiche_apps::client] 0.0.0.0:51249: recv() would block
[2025-04-30T10:30:02.242055184Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.242059422Z TRACE quiche_apps::client] 0.0.0.0:51249 -> 193.167.100.100:443: done writing
[2025-04-30T10:30:02.560432185Z TRACE quiche_apps::client] timed out
[2025-04-30T10:30:02.560462802Z TRACE quiche] 7feaa0ef24feb4a8a6bebb2cada6148255a0b000 draining timeout expired
[2025-04-30T10:30:02.560512144Z TRACE quiche_apps::client] done reading
[2025-04-30T10:30:02.560517024Z INFO  quiche_apps::client] connection closed, recv=18 sent=23 lost=6 retrans=0 sent_bytes=1841 recv_bytes=18767 lost_bytes=193 [local_addr=0.0.0.0:51249 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=18 sent=23 lost=6 retrans=0 rtt=45.303414ms min_rtt=Some(31.914606ms) rttvar=17.689682ms cwnd=9450 sent_bytes=1841 recv_bytes=18767 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=3445]