[2025-04-30T19:26:23.658758894Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T19:26:23.659039679Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:55501 with scid eb40e0aef75590b10a21793be8fb7ea943e9590d
[2025-04-30T19:26:23.659080465Z TRACE quiche::tls] eb40e0aef75590b10a21793be8fb7ea943e9590d write message lvl=Initial len=266
[2025-04-30T19:26:23.659099611Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Initial version=1 dcid=d3f348e9c8b8d50cb8a68f339bc38834 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=270 pn=0 
[2025-04-30T19:26:23.659102747Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm CRYPTO off=0 len=266
[2025-04-30T19:26:23.659117113Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=998.929037ms 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: 376, tv_nsec: 661322125 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 661322125 }, 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: 376, tv_nsec: 661322125 }, next_time: Instant { tv_sec: 376, tv_nsec: 661322125 }, 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-30T19:26:23.659174461Z TRACE quiche_apps::client] written 1200
[2025-04-30T19:26:23.700809610Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:23.700825480Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Initial version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 token= len=118 pn=0 
[2025-04-30T19:26:23.700836721Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm ACK delay=453 blocks=[0..0] ecn_counts=None
[2025-04-30T19:26:23.700842993Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet newly acked 0
[2025-04-30T19:26:23.700848373Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=0 len=90
[2025-04-30T19:26:23.700952958Z TRACE quiche::tls] eb40e0aef75590b10a21793be8fb7ea943e9590d set write secret lvl=Handshake
[2025-04-30T19:26:23.700964801Z TRACE quiche::tls] eb40e0aef75590b10a21793be8fb7ea943e9590d set read secret lvl=Handshake
[2025-04-30T19:26:23.700995227Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1007 pn=1 
[2025-04-30T19:26:23.701002231Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=0 len=985
[2025-04-30T19:26:23.701020795Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:23.701026336Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:23.701029371Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:23.701055089Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Initial version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=1 
[2025-04-30T19:26:23.701058776Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=28 blocks=[0..0] ecn_counts=None
[2025-04-30T19:26:23.701067212Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=125.082209ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 403942, last_update: Instant { tv_sec: 376, tv_nsec: 703290999 }, next_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, 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-30T19:26:23.701095485Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=2 
[2025-04-30T19:26:23.701099052Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=12 blocks=[1..1] ecn_counts=None
[2025-04-30T19:26:23.701105724Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=125.043777ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 403942, last_update: Instant { tv_sec: 376, tv_nsec: 703290999 }, next_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, 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-30T19:26:23.701118889Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d dropped epoch 0 state
[2025-04-30T19:26:23.701157150Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:23.701163773Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:23.701768003Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:23.701775947Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=2 
[2025-04-30T19:26:23.701782800Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=985 len=1140
[2025-04-30T19:26:23.701793911Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:23.701797618Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:23.701800063Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:23.701808027Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=3 
[2025-04-30T19:26:23.701810562Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=4 blocks=[1..2] ecn_counts=None
[2025-04-30T19:26:23.701821833Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=124.552338ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 403942, last_update: Instant { tv_sec: 376, tv_nsec: 703290999 }, next_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, 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-30T19:26:23.701856308Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:23.701861097Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:23.702749628Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:23.702760318Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=3 
[2025-04-30T19:26:23.702769104Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=2125 len=1140
[2025-04-30T19:26:23.702782109Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:23.702786938Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:23.702790114Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:23.702800864Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=4 
[2025-04-30T19:26:23.702804370Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=5 blocks=[1..3] ecn_counts=None
[2025-04-30T19:26:23.702812486Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=123.561465ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 403942, last_update: Instant { tv_sec: 376, tv_nsec: 703290999 }, next_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, 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-30T19:26:23.702853933Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:23.702859153Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:23.827035199Z TRACE quiche_apps::client] timed out
[2025-04-30T19:26:23.827048203Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d loss detection timeout expired
[2025-04-30T19:26:23.827052602Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=250.649014ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 403942, last_update: Instant { tv_sec: 376, tv_nsec: 703290999 }, next_time: Instant { tv_sec: 376, tv_nsec: 703291029 }, 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-30T19:26:23.827070184Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:23.827087296Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=7 pn=5 
[2025-04-30T19:26:23.827091194Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=15540 blocks=[1..3] ecn_counts=None
[2025-04-30T19:26:23.827095552Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm PING mtu_probe=None
[2025-04-30T19:26:23.827106152Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=250.623736ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=61 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 61, rate: 403942, last_update: Instant { tv_sec: 376, tv_nsec: 829351038 }, next_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, 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-04-30T19:26:23.827158109Z TRACE quiche_apps::client] written 61 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:23.827164661Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.078466292Z TRACE quiche_apps::client] timed out
[2025-04-30T19:26:24.078479587Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d loss detection timeout expired
[2025-04-30T19:26:24.078484126Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=249.899935ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=61 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 61, rate: 403942, last_update: Instant { tv_sec: 376, tv_nsec: 829351038 }, next_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, 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-04-30T19:26:24.078512829Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.078533999Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=9 pn=6 
[2025-04-30T19:26:24.078537836Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=46971 blocks=[1..3] ecn_counts=None
[2025-04-30T19:26:24.078542335Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm PING mtu_probe=None
[2025-04-30T19:26:24.078555068Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=501.272951ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=124 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 403942, last_update: Instant { tv_sec: 377, tv_nsec: 80794545 }, next_time: Instant { tv_sec: 377, tv_nsec: 80794545 }, 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-30T19:26:24.078608508Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.078620861Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=9 pn=7 
[2025-04-30T19:26:24.078624398Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=46982 blocks=[1..3] ecn_counts=None
[2025-04-30T19:26:24.078627944Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm PING mtu_probe=None
[2025-04-30T19:26:24.078635008Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=501.287307ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 403942, last_update: Instant { tv_sec: 377, tv_nsec: 80794545 }, next_time: Instant { tv_sec: 377, tv_nsec: 80794545 }, 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-30T19:26:24.078679531Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.078685502Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.580748645Z TRACE quiche_apps::client] timed out
[2025-04-30T19:26:24.580761359Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d loss detection timeout expired
[2025-04-30T19:26:24.580765486Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=500.465666ms latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 403942, last_update: Instant { tv_sec: 377, tv_nsec: 80794545 }, next_time: Instant { tv_sec: 377, tv_nsec: 80794545 }, 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-30T19:26:24.580781667Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.580801103Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=9 pn=8 
[2025-04-30T19:26:24.580804409Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=109755 blocks=[1..3] ecn_counts=None
[2025-04-30T19:26:24.580808066Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm PING mtu_probe=None
[2025-04-30T19:26:24.580819468Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=1.002585705s latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=250 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 403942, last_update: Instant { tv_sec: 377, tv_nsec: 583062410 }, next_time: Instant { tv_sec: 377, tv_nsec: 583062410 }, 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-30T19:26:24.580880722Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.580891352Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=9 pn=9 
[2025-04-30T19:26:24.580894277Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=109766 blocks=[1..3] ecn_counts=None
[2025-04-30T19:26:24.580897253Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm PING mtu_probe=None
[2025-04-30T19:26:24.580903154Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=1.002600372s latest_rtt=41.775763ms srtt=41.775763ms min_rtt=41.775763ms rttvar=20.887881ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=313 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, first_sent_time: Instant { tv_sec: 376, tv_nsec: 829351038 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 7971, is_app_limited: true, interval: 41.775763ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 661322125 }), send_elapsed: 0ns, ack_elapsed: 41.775763ms, rtt: 41.775763ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 403942, last_update: Instant { tv_sec: 377, tv_nsec: 583062410 }, next_time: Instant { tv_sec: 377, tv_nsec: 583062410 }, 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-30T19:26:24.580930525Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.580934864Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.613188786Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.613204986Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=4 
[2025-04-30T19:26:24.613216748Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm ACK delay=45 blocks=[8..8] ecn_counts=None
[2025-04-30T19:26:24.613222068Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet newly acked 8
[2025-04-30T19:26:24.613227709Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet 5 lost on epoch 1
[2025-04-30T19:26:24.613231075Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet 6 lost on epoch 1
[2025-04-30T19:26:24.613234261Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet 7 lost on epoch 1
[2025-04-30T19:26:24.613240202Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=0 len=1136
[2025-04-30T19:26:24.613259027Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.613264498Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.613267744Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.613279776Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=10 
[2025-04-30T19:26:24.613283333Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=9 blocks=[4..4] ecn_counts=None
[2025-04-30T19:26:24.613292290Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=80.222616ms latest_rtt=32.415265ms srtt=40.6057ms min_rtt=32.415265ms rttvar=18.006034ms cwnd=9450 ssthresh=9450 bytes_in_flight=63 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 615477675 }) Rate { delivered: 396, delivered_time: Instant { tv_sec: 377, tv_nsec: 615477675 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 583062410 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { delivery_rate: 80, is_app_limited: true, interval: 786.126637ms, delivered: 63, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 290907, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 583062410 }, max_datagram_size: 1350, last_packet_size: None, iv: 433.128µ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-30T19:26:24.613353153Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.613359856Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.614155566Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.614171004Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=5 
[2025-04-30T19:26:24.614181714Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=1136 len=1140
[2025-04-30T19:26:24.614220577Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.614226248Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.614229434Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.614241516Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=11 
[2025-04-30T19:26:24.614245273Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=8 blocks=[4..5] ecn_counts=None
[2025-04-30T19:26:24.614255102Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=79.259734ms latest_rtt=32.415265ms srtt=40.6057ms min_rtt=32.415265ms rttvar=18.006034ms cwnd=9450 ssthresh=9450 bytes_in_flight=63 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 615477675 }) Rate { delivered: 396, delivered_time: Instant { tv_sec: 377, tv_nsec: 615477675 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 583062410 }, end_of_app_limited: 9, last_sent_packet: 11, largest_acked: 8, rate_sample: RateSample { delivery_rate: 80, is_app_limited: true, interval: 786.126637ms, delivered: 63, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 290907, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.614309303Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.614320294Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.615142962Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.615155696Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=6 
[2025-04-30T19:26:24.615164602Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=2276 len=1140
[2025-04-30T19:26:24.615179089Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.615183197Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.615185772Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.615195350Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=12 
[2025-04-30T19:26:24.615198285Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=5 blocks=[4..6] ecn_counts=None
[2025-04-30T19:26:24.615205709Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=78.309267ms latest_rtt=32.415265ms srtt=40.6057ms min_rtt=32.415265ms rttvar=18.006034ms cwnd=9450 ssthresh=9450 bytes_in_flight=63 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 615477675 }) Rate { delivered: 396, delivered_time: Instant { tv_sec: 377, tv_nsec: 615477675 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 583062410 }, end_of_app_limited: 9, last_sent_packet: 12, largest_acked: 8, rate_sample: RateSample { delivery_rate: 80, is_app_limited: true, interval: 786.126637ms, delivered: 63, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 290907, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.615263066Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.615271582Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.616126112Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.616134337Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=7 
[2025-04-30T19:26:24.616141330Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=3416 len=1140
[2025-04-30T19:26:24.616152461Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.616156188Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.616158613Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.616166948Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=13 
[2025-04-30T19:26:24.616169683Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=4 blocks=[4..7] ecn_counts=None
[2025-04-30T19:26:24.616176195Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=77.33884ms latest_rtt=32.415265ms srtt=40.6057ms min_rtt=32.415265ms rttvar=18.006034ms cwnd=9450 ssthresh=9450 bytes_in_flight=63 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 615477675 }) Rate { delivered: 396, delivered_time: Instant { tv_sec: 377, tv_nsec: 615477675 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 583062410 }, end_of_app_limited: 9, last_sent_packet: 13, largest_acked: 8, rate_sample: RateSample { delivery_rate: 80, is_app_limited: true, interval: 786.126637ms, delivered: 63, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 290907, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.616222723Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.616231259Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.617073194Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.617081200Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=8 
[2025-04-30T19:26:24.617088053Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=4556 len=1140
[2025-04-30T19:26:24.617098993Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.617103852Z TRACE quiche_apps::client] got 60 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.617107018Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=23 pn=9 
[2025-04-30T19:26:24.617111967Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm ACK delay=8 blocks=[8..9] ecn_counts=None
[2025-04-30T19:26:24.617115414Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet newly acked 9
[2025-04-30T19:26:24.617124130Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 60 bytes
[2025-04-30T19:26:24.617127667Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.617130131Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.617138407Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=14 
[2025-04-30T19:26:24.617141092Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=3 blocks=[4..9] ecn_counts=None
[2025-04-30T19:26:24.617146652Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 615477675 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.617192127Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.617200833Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.645076831Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.645089615Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=10 
[2025-04-30T19:26:24.645098262Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=5696 len=1140
[2025-04-30T19:26:24.645111656Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.645115784Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.645118309Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.645128187Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=15 
[2025-04-30T19:26:24.645132115Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=4 blocks=[4..10] ecn_counts=None
[2025-04-30T19:26:24.645141072Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 615477675 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 13, last_sent_packet: 15, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.645197267Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.645207586Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.646683389Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.646691714Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=11 
[2025-04-30T19:26:24.646698567Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=6836 len=1140
[2025-04-30T19:26:24.646709718Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.646713325Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.646715769Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.646723864Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=16 
[2025-04-30T19:26:24.646747979Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=4 blocks=[4..11] ecn_counts=None
[2025-04-30T19:26:24.646761795Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 615477675 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 13, last_sent_packet: 16, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.646802091Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.646810547Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.647667751Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.647673171Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=1163 pn=12 
[2025-04-30T19:26:24.647679774Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=7976 len=1140
[2025-04-30T19:26:24.647689913Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.647693459Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.647695844Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.647703839Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=5 pn=17 
[2025-04-30T19:26:24.647706373Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=3 blocks=[4..12] ecn_counts=None
[2025-04-30T19:26:24.647712465Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 615477675 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 13, last_sent_packet: 17, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.647774932Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.647786994Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.648564149Z TRACE quiche_apps::client] got 1093 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.648569359Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Handshake version=1 dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d scid=f0c9f534522c0841 len=891 pn=13 
[2025-04-30T19:26:24.648575791Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=9116 len=868
[2025-04-30T19:26:24.649090695Z TRACE quiche::tls] eb40e0aef75590b10a21793be8fb7ea943e9590d write message lvl=Handshake len=52
[2025-04-30T19:26:24.649097147Z TRACE quiche::tls] eb40e0aef75590b10a21793be8fb7ea943e9590d set write secret lvl=OneRTT
[2025-04-30T19:26:24.649106534Z TRACE quiche::tls] eb40e0aef75590b10a21793be8fb7ea943e9590d set read secret lvl=OneRTT
[2025-04-30T19:26:24.649121472Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d connection established: proto=Ok("h3") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(d3f348e9c8b8d50cb8a68f339bc38834), max_idle_timeout: 60000, stateless_reset_token: Some(162611306021258065440860021558703211007), max_udp_payload_size: 65527, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 1048576, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 1048576, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(f0c9f534522c0841), retry_source_connection_id: None, max_datagram_frame_size: Some(65536), unknown_params: None }
[2025-04-30T19:26:24.649145157Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=144 pn=14 
[2025-04-30T19:26:24.649151068Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm CRYPTO off=0 len=89
[2025-04-30T19:26:24.649165244Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=3 off=0 len=19 fin=false
[2025-04-30T19:26:24.649169552Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T19:26:24.649172558Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T19:26:24.649178088Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1093 bytes
[2025-04-30T19:26:24.649182316Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.649184801Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.649202644Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm SETTINGS stream=2 len=18
[2025-04-30T19:26:24.649214627Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d open GREASE stream 14
[2025-04-30T19:26:24.649236668Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm GREASE stream=0 len=0
[2025-04-30T19:26:24.649243100Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm GREASE stream=0 len=18
[2025-04-30T19:26:24.649250423Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm HEADERS stream=0 len=30 fin=true
[2025-04-30T19:26:24.649256685Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /proujxqnkm", "user-agent: quiche"]
[2025-04-30T19:26:24.649303934Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 3 is readable
[2025-04-30T19:26:24.649309604Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 3
[2025-04-30T19:26:24.649313932Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d open peer's control stream 3
[2025-04-30T19:26:24.649317199Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 3
[2025-04-30T19:26:24.649324101Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 3
[2025-04-30T19:26:24.649327648Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 16 bytes on stream 3
[2025-04-30T19:26:24.649331355Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm SETTINGS max_field_section=None, qpack_max_table=Some(4096), qpack_blocked=Some(16) raw=Some([(1, 4096), (7, 16), (8, 1), (33, 1), (51, 1), (727725890, 1)]), additional_settings=Some([(33, 1), (727725890, 1)]) stream=3 payload_len=16
[2025-04-30T19:26:24.649340642Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 7 is readable
[2025-04-30T19:26:24.649344039Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 7
[2025-04-30T19:26:24.649347505Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 11 is readable
[2025-04-30T19:26:24.649351152Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 11
[2025-04-30T19:26:24.649363395Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Handshake version=1 dcid=f0c9f534522c0841 scid=eb40e0aef75590b10a21793be8fb7ea943e9590d len=62 pn=18 
[2025-04-30T19:26:24.649366380Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=99 blocks=[4..13] ecn_counts=None
[2025-04-30T19:26:24.649369456Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm CRYPTO off=0 len=52
[2025-04-30T19:26:24.649377842Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=98.408587ms latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=116 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.649399082Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10694 out_len=1234 left=1203
[2025-04-30T19:26:24.649406075Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=309 pn=19 
[2025-04-30T19:26:24.649408619Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=31 blocks=[14..14] ecn_counts=None
[2025-04-30T19:26:24.649411235Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[7b, 51, 5e, 6d, 93, dd, 10, 15, 64, db, 9f, 69, 73, a4, 10, 81, bc, cd, 0a, 0e] reset_token=[2b, 50, e3, 75, 4c, 2b, a0, 6a, 69, dc, 7e, a8, c3, f5, 7b, 0d]
[2025-04-30T19:26:24.649418568Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[56, b8, 84, 89, 64, 0e, 21, 30, ff, 81, a8, 73, a7, 0d, 9d, 38, f6, dd, 3b, f9] reset_token=[d0, 90, f9, bd, 8d, 86, 81, 6e, 30, a2, 94, 6b, c8, e8, f0, 2e]
[2025-04-30T19:26:24.649424720Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[d1, 0a, 65, e9, 04, cd, 31, 4d, 00, 4b, 97, 8e, 4a, 6c, 9a, 89, 10, 48, ff, 23] reset_token=[00, c3, 86, ee, a8, 8d, cf, 1d, 0f, d3, fa, 69, 76, 27, 59, 1a]
[2025-04-30T19:26:24.649434648Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[24, 8f, 58, 5d, d0, f5, eb, 11, 39, bc, 29, 59, d4, ad, 52, 74, fc, 76, 15, ef] reset_token=[23, 54, 69, af, e8, ba, c6, ae, 33, e1, 15, b2, 3e, 61, 6d, 44]
[2025-04-30T19:26:24.649440469Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[31, b1, f4, f2, 1f, 9b, 75, 41, df, 3a, 3d, 9b, be, af, 68, 88, ed, 7f, a9, b7] reset_token=[2a, 3d, 69, 87, 16, 61, df, 78, 44, 40, b7, cf, 32, 3f, 99, a1]
[2025-04-30T19:26:24.649446330Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[85, 52, 9a, f7, 6b, 1b, 6b, f4, a2, d7, 0a, 04, 6a, 32, 01, 67, 8a, ae, 57, 73] reset_token=[8b, 04, 7a, 49, 5f, fb, 2c, df, d3, 8f, 21, 0a, fa, 50, ba, 93]
[2025-04-30T19:26:24.649452311Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[72, 48, 89, be, 69, 02, ce, 53, 12, 5d, 7c, be, 17, b1, 63, 95, 09, de, 04, d5] reset_token=[39, dc, 6b, 51, 02, 86, 93, 63, 8a, 68, a4, 24, 25, cc, 68, 3b]
[2025-04-30T19:26:24.649458292Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T19:26:24.649468201Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=98.318308ms latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=451 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 18, last_sent_packet: 19, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.649510500Z TRACE quiche_apps::client] written 451 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.649520659Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10024 out_len=1350 left=1324
[2025-04-30T19:26:24.649526480Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=6 pn=20 
[2025-04-30T19:26:24.649530668Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T19:26:24.649539114Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=98.247125ms latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=483 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 19, last_sent_packet: 20, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.649584118Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.649593806Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=false hs_sent=false cwnd_avail=9960 out_len=1350 left=1324
[2025-04-30T19:26:24.649599597Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=6 pn=21 
[2025-04-30T19:26:24.649603645Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T19:26:24.649612852Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=98.173226ms latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=515 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 20, last_sent_packet: 21, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.649648408Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.649657235Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=false hs_sent=false cwnd_avail=9896 out_len=1350 left=1324
[2025-04-30T19:26:24.649662826Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=73 pn=22 
[2025-04-30T19:26:24.649666703Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T19:26:24.649676291Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=98.109978ms latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=614 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 21, last_sent_packet: 22, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.649716426Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.649740501Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=false hs_sent=false cwnd_avail=9698 out_len=1350 left=1324
[2025-04-30T19:26:24.649750199Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=31 pn=23 
[2025-04-30T19:26:24.649754307Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T19:26:24.649764646Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=98.021542ms latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=671 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 22, last_sent_packet: 23, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 615547425 }, next_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, 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-30T19:26:24.649803409Z TRACE quiche_apps::client] written 57 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.649810712Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.681448771Z TRACE quiche_apps::client] got 236 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.681461455Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=215 pn=15 
[2025-04-30T19:26:24.681468899Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm HANDSHAKE_DONE
[2025-04-30T19:26:24.681474329Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d dropped epoch 1 state
[2025-04-30T19:26:24.681478988Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[3d, 3e, 79, 72, f6, 6a, b4, 2d] reset_token=[27, 9f, 88, 16, f7, a8, 0a, 6d, dc, e0, 4b, c6, e6, 51, 31, 08]
[2025-04-30T19:26:24.681486802Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[1b, 6f, a9, bf, 0f, a7, 32, 29] reset_token=[ce, e0, 87, 05, 9e, 0a, de, 51, d9, a8, 8f, 8c, af, 99, 12, 64]
[2025-04-30T19:26:24.681492844Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b4, a7, 8c, 88, ff, 71, b7, 1f] reset_token=[66, d7, d1, 76, 67, cc, 08, 16, 68, 90, 71, 7a, e2, 01, c0, ba]
[2025-04-30T19:26:24.681504025Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[ba, cd, 35, 66, 4d, 34, b7, 67] reset_token=[0a, 13, 2b, 25, c7, 3a, d0, 9b, 6b, fa, 63, 1a, d2, 9f, 77, c6]
[2025-04-30T19:26:24.681510226Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[14, cc, 32, f3, a5, e5, 2a, 03] reset_token=[b4, 5c, 5c, 53, ca, 80, b9, 17, f2, d4, 90, 08, 1e, 7a, e0, c5]
[2025-04-30T19:26:24.681515756Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[30, 27, 89, 43, 14, c6, 4c, 4d] reset_token=[31, 09, 14, ef, 2e, 1e, 56, f0, 45, fd, 84, 85, 70, 59, 1e, 35]
[2025-04-30T19:26:24.681521277Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[fc, 0f, 8c, b0, 43, 7e, d9, e7] reset_token=[0e, a0, e5, 45, e9, d5, e9, 47, eb, 1e, 63, 1a, 01, e5, c4, 12]
[2025-04-30T19:26:24.681543999Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 236 bytes
[2025-04-30T19:26:24.681548127Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.681550692Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.681558186Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=9700 out_len=1350 left=1319
[2025-04-30T19:26:24.681561863Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=5 pn=24 
[2025-04-30T19:26:24.681564498Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=12 blocks=[14..15] ecn_counts=None
[2025-04-30T19:26:24.681571431Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=91.581164ms latest_rtt=36.31939ms srtt=40.06191ms min_rtt=32.415265ms rttvar=14.592102ms cwnd=9450 ssthresh=9450 bytes_in_flight=555 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 616942253 }, end_of_app_limited: 22, last_sent_packet: 24, largest_acked: 9, rate_sample: RateSample { delivery_rate: 160, is_app_limited: true, interval: 786.126637ms, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 376, tv_nsec: 829351038 }), send_elapsed: 753.711372ms, ack_elapsed: 786.126637ms, rtt: 32.415265ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 294856, last_update: Instant { tv_sec: 377, tv_nsec: 683830223 }, next_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, 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-30T19:26:24.681618469Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.681626223Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.681637414Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.681644067Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=23 pn=16 
[2025-04-30T19:26:24.681650359Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm ACK delay=21 blocks=[19..20] ecn_counts=None
[2025-04-30T19:26:24.681653945Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet newly acked 19
[2025-04-30T19:26:24.681657442Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet newly acked 20
[2025-04-30T19:26:24.681674133Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 44 bytes
[2025-04-30T19:26:24.681678060Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.681680555Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.681684933Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.685702796Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.685712554Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=23 pn=17 
[2025-04-30T19:26:24.685718435Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm ACK delay=17 blocks=[19..23] ecn_counts=None
[2025-04-30T19:26:24.685721892Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet newly acked 21
[2025-04-30T19:26:24.685724316Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet newly acked 22
[2025-04-30T19:26:24.685747860Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d packet newly acked 23
[2025-04-30T19:26:24.685762598Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 44 bytes
[2025-04-30T19:26:24.685766595Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.685768970Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.685773738Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.688097087Z TRACE quiche_apps::client] got 163 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.688105994Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=142 pn=18 
[2025-04-30T19:26:24.688112126Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=0 off=0 len=120 fin=false
[2025-04-30T19:26:24.688118828Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 163 bytes
[2025-04-30T19:26:24.688122435Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.688124879Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.688127464Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 0 is readable
[2025-04-30T19:26:24.688131402Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 0
[2025-04-30T19:26:24.688134948Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 0
[2025-04-30T19:26:24.688137944Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 0
[2025-04-30T19:26:24.688142182Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 117 bytes on stream 0
[2025-04-30T19:26:24.688144907Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm HEADERS stream=0 payload_len=117
[2025-04-30T19:26:24.688148243Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T19:26:24.688150798Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-04-30T19:26:24.688155186Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[97, 105, 111, 113, 117, 105, 99, 47, 49, 46, 50, 46, 48]
[2025-04-30T19:26:24.688161708Z TRACE quiche::h3::qpack::decoder] Literal name_idx=6 static=true value=[87, 101, 100, 44, 32, 51, 48, 32, 65, 112, 114, 32, 50, 48, 50, 53, 32, 49, 57, 58, 50, 54, 58, 50, 52, 32, 71, 77, 84]
[2025-04-30T19:26:24.688167399Z TRACE quiche::h3::qpack::decoder] Literal name_idx=44 static=true value=[116, 101, 120, 116, 47, 112, 108, 97, 105, 110, 59, 32, 99, 104, 97, 114, 115, 101, 116, 61, 117, 116, 102, 45, 56]
[2025-04-30T19:26:24.688171767Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[53, 49, 50, 48]
[2025-04-30T19:26:24.688175354Z TRACE quiche::h3::qpack::decoder] Literal name_idx=10 static=true value=[87, 101, 100, 44, 32, 51, 48, 32, 65, 112, 114, 32, 50, 48, 50, 53, 32, 49, 57, 58, 50, 54, 58, 50, 49, 32, 71, 77, 84]
[2025-04-30T19:26:24.688185884Z TRACE quiche::h3::qpack::decoder] Literal name_idx=7 static=true value=[34, 48, 52, 57, 49, 49, 48, 102, 51, 57, 55, 53, 52, 56, 98, 54, 98, 48, 56, 52, 53, 100, 57, 54, 53, 97, 49, 98, 49, 100, 99, 48, 101, 34]
[2025-04-30T19:26:24.688195381Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "aioquic/1.2.0"), ("date", "Wed, 30 Apr 2025 19:26:24 GMT"), ("content-type", "text/plain; charset=utf-8"), ("content-length", "5120"), ("last-modified", "Wed, 30 Apr 2025 19:26:21 GMT"), ("etag", "\"049110f397548b6b0845d965a1b1dc0e\"")] on stream id 0
[2025-04-30T19:26:24.688206082Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10857 out_len=1350 left=1319
[2025-04-30T19:26:24.688209448Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=5 pn=25 
[2025-04-30T19:26:24.688211922Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=12 blocks=[15..18] ecn_counts=None
[2025-04-30T19:26:24.688218535Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=71.044132ms srtt=46.843005ms min_rtt=32.415265ms rttvar=20.098639ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 1014, delivered_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, end_of_app_limited: 24, last_sent_packet: 25, largest_acked: 23, rate_sample: RateSample { delivery_rate: 8286, is_app_limited: true, interval: 66.975684ms, delivered: 555, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 377, tv_nsec: 616942253 }), send_elapsed: 0ns, ack_elapsed: 66.975684ms, rtt: 66.975684ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 252172, last_update: Instant { tv_sec: 377, tv_nsec: 683830223 }, next_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, 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-30T19:26:24.688262427Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.688270021Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.689465388Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.689473143Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=1179 pn=19 
[2025-04-30T19:26:24.689480427Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=0 off=120 len=1155 fin=false
[2025-04-30T19:26:24.689487400Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.689490987Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.689493411Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.689495936Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 0 is readable
[2025-04-30T19:26:24.689499893Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 0
[2025-04-30T19:26:24.689503209Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 0
[2025-04-30T19:26:24.689506235Z TRACE quiche::h3::stream] eb40e0aef75590b10a21793be8fb7ea943e9590d read 1 bytes on stream 0
[2025-04-30T19:26:24.689508549Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm DATA stream=0 wire_payload_len=5120
[2025-04-30T19:26:24.689518598Z DEBUG quiche_apps::common] got 1152 bytes of response data on stream 0
[2025-04-30T19:26:24.689525391Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10857 out_len=1350 left=1319
[2025-04-30T19:26:24.689528567Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=5 pn=26 
[2025-04-30T19:26:24.689530911Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=6 blocks=[15..19] ecn_counts=None
[2025-04-30T19:26:24.689536782Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=71.044132ms srtt=46.843005ms min_rtt=32.415265ms rttvar=20.098639ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 1014, delivered_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, end_of_app_limited: 24, last_sent_packet: 26, largest_acked: 23, rate_sample: RateSample { delivery_rate: 8286, is_app_limited: true, interval: 66.975684ms, delivered: 555, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 377, tv_nsec: 616942253 }), send_elapsed: 0ns, ack_elapsed: 66.975684ms, rtt: 66.975684ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 252172, last_update: Instant { tv_sec: 377, tv_nsec: 683830223 }, next_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, 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-30T19:26:24.689575104Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.689582067Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.695951198Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.695960396Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=1179 pn=20 
[2025-04-30T19:26:24.695967919Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=0 off=1275 len=1155 fin=false
[2025-04-30T19:26:24.695976055Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.695980333Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.695983298Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.695986605Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 0 is readable
[2025-04-30T19:26:24.695991915Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:26:24.695999458Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10857 out_len=1350 left=1319
[2025-04-30T19:26:24.696003236Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=5 pn=27 
[2025-04-30T19:26:24.696006161Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=4 blocks=[15..20] ecn_counts=None
[2025-04-30T19:26:24.696013114Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=71.044132ms srtt=46.843005ms min_rtt=32.415265ms rttvar=20.098639ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 1014, delivered_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, end_of_app_limited: 24, last_sent_packet: 27, largest_acked: 23, rate_sample: RateSample { delivery_rate: 8286, is_app_limited: true, interval: 66.975684ms, delivered: 555, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 377, tv_nsec: 616942253 }), send_elapsed: 0ns, ack_elapsed: 66.975684ms, rtt: 66.975684ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 252172, last_update: Instant { tv_sec: 377, tv_nsec: 683830223 }, next_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, 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-30T19:26:24.696064230Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.696072936Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.696950338Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.696959475Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=1179 pn=21 
[2025-04-30T19:26:24.696967170Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=0 off=2430 len=1155 fin=false
[2025-04-30T19:26:24.696974924Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.696979182Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.696982228Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.696985294Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 0 is readable
[2025-04-30T19:26:24.696990554Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:26:24.696998088Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10857 out_len=1350 left=1319
[2025-04-30T19:26:24.697001885Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=5 pn=28 
[2025-04-30T19:26:24.697004810Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=4 blocks=[15..21] ecn_counts=None
[2025-04-30T19:26:24.697011824Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=71.044132ms srtt=46.843005ms min_rtt=32.415265ms rttvar=20.098639ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 1014, delivered_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, end_of_app_limited: 24, last_sent_packet: 28, largest_acked: 23, rate_sample: RateSample { delivery_rate: 8286, is_app_limited: true, interval: 66.975684ms, delivered: 555, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 377, tv_nsec: 616942253 }), send_elapsed: 0ns, ack_elapsed: 66.975684ms, rtt: 66.975684ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 252172, last_update: Instant { tv_sec: 377, tv_nsec: 683830223 }, next_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, 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-30T19:26:24.697056637Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.697064913Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.702254286Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.702270447Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=1179 pn=22 
[2025-04-30T19:26:24.702278662Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=0 off=3585 len=1155 fin=false
[2025-04-30T19:26:24.702286857Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 1200 bytes
[2025-04-30T19:26:24.702291235Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.702294221Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.702297507Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 0 is readable
[2025-04-30T19:26:24.702302827Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:26:24.702310882Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10857 out_len=1350 left=1319
[2025-04-30T19:26:24.702314659Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=5 pn=29 
[2025-04-30T19:26:24.702317585Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm ACK delay=5 blocks=[15..22] ecn_counts=None
[2025-04-30T19:26:24.702324798Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=none latest_rtt=71.044132ms srtt=46.843005ms min_rtt=32.415265ms rttvar=20.098639ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 683948626 }) Rate { delivered: 1014, delivered_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, end_of_app_limited: 24, last_sent_packet: 29, largest_acked: 23, rate_sample: RateSample { delivery_rate: 8286, is_app_limited: true, interval: 66.975684ms, delivered: 555, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 377, tv_nsec: 616942253 }), send_elapsed: 0ns, ack_elapsed: 66.975684ms, rtt: 66.975684ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 252172, last_update: Instant { tv_sec: 377, tv_nsec: 683830223 }, next_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, 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-30T19:26:24.702369602Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.702378329Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:24.702704539Z TRACE quiche_apps::client] got 548 bytes from 193.167.100.100:443 to 0.0.0.0:55501
[2025-04-30T19:26:24.702710480Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx pkt Short dcid=eb40e0aef75590b10a21793be8fb7ea943e9590d key_phase=false len=527 pn=23 
[2025-04-30T19:26:24.702717253Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d rx frm STREAM id=0 off=4740 len=503 fin=true
[2025-04-30T19:26:24.702745826Z TRACE quiche_apps::client] 0.0.0.0:55501: processed 548 bytes
[2025-04-30T19:26:24.702753420Z TRACE quiche_apps::client] 0.0.0.0:55501: recv() would block
[2025-04-30T19:26:24.702757067Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:24.702760203Z TRACE quiche::h3] eb40e0aef75590b10a21793be8fb7ea943e9590d stream id 0 is readable
[2025-04-30T19:26:24.702766836Z DEBUG quiche_apps::common] got 503 bytes of response data on stream 0
[2025-04-30T19:26:24.702770142Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T19:26:24.702773418Z INFO  quiche_apps::common] 1/1 response(s) received in 1.043595491s, closing...
[2025-04-30T19:26:24.702781413Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10857 out_len=1350 left=1324
[2025-04-30T19:26:24.702791692Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx pkt Short dcid=f0c9f534522c0841 key_phase=false len=11 pn=30 
[2025-04-30T19:26:24.702794858Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T19:26:24.702803544Z TRACE quiche::recovery::congestion::recovery] eb40e0aef75590b10a21793be8fb7ea943e9590d timer=152.211953ms latest_rtt=71.044132ms srtt=46.843005ms min_rtt=32.415265ms rttvar=20.098639ms cwnd=9450 ssthresh=9450 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 377, tv_nsec: 737004386 }) Rate { delivered: 1014, delivered_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, first_sent_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, end_of_app_limited: 29, last_sent_packet: 30, largest_acked: 23, rate_sample: RateSample { delivery_rate: 8286, is_app_limited: true, interval: 66.975684ms, delivered: 555, prior_delivered: 459, prior_time: Some(Instant { tv_sec: 377, tv_nsec: 616942253 }), send_elapsed: 0ns, ack_elapsed: 66.975684ms, rtt: 66.975684ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 252172, last_update: Instant { tv_sec: 377, tv_nsec: 683830223 }, next_time: Instant { tv_sec: 377, tv_nsec: 683830223 }, 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-30T19:26:24.702848478Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:55501 to 193.167.100.100:443
[2025-04-30T19:26:24.702856333Z TRACE quiche_apps::client] 0.0.0.0:55501 -> 193.167.100.100:443: done writing
[2025-04-30T19:26:25.085292065Z TRACE quiche_apps::client] timed out
[2025-04-30T19:26:25.085307755Z TRACE quiche] eb40e0aef75590b10a21793be8fb7ea943e9590d draining timeout expired
[2025-04-30T19:26:25.085344133Z TRACE quiche_apps::client] done reading
[2025-04-30T19:26:25.085348170Z INFO  quiche_apps::client] connection closed, recv=24 sent=31 lost=6 retrans=0 sent_bytes=2249 recv_bytes=20188 lost_bytes=187 [local_addr=0.0.0.0:55501 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=24 sent=31 lost=6 retrans=0 rtt=46.843005ms min_rtt=Some(32.415265ms) rttvar=20.098639ms cwnd=9450 sent_bytes=2249 recv_bytes=20188 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=8286]