[2025-04-30T10:38:26.634879376Z INFO  quiche_server] listening on [::]:443
[2025-04-30T10:38:26.634908281Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T10:38:26.634917538Z TRACE quiche_server] GSO detected: false
[2025-04-30T10:38:27.457615358Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38675 to [::]:443
[2025-04-30T10:38:27.457646537Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:38:27.457654502Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:38:27.457739502Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38675 to [::]:443
[2025-04-30T10:38:27.457745683Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:38:27.457749461Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:38:27.457778095Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38675 to [::]:443
[2025-04-30T10:38:27.457785238Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:38:27.457790298Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:38:27.457817799Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:27.687286561Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:27.687310166Z TRACE quiche_server] got packet Initial version=1 dcid=efe6e169166636f5bd5aaf02344499a4445cb13c scid=9288183d6a27c02a token=
[2025-04-30T10:38:27.687320305Z DEBUG quiche_server] New connection: dcid=efe6e169166636f5bd5aaf02344499a4445cb13c scid=42f896e89a88574ef9274988d4e71a4c78a24e17
[2025-04-30T10:38:27.687539367Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Initial version=1 dcid=efe6e169166636f5bd5aaf02344499a4445cb13c scid=9288183d6a27c02a token= len=1162 pn=0 
[2025-04-30T10:38:27.687554646Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm CRYPTO off=0 len=248
[2025-04-30T10:38:27.687582108Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T10:38:27.687586726Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T10:38:27.687690081Z TRACE quiche::tls] 42f896e89a88574ef9274988d4e71a4c78a24e17 write message lvl=Initial len=90
[2025-04-30T10:38:27.687718484Z TRACE quiche::tls] 42f896e89a88574ef9274988d4e71a4c78a24e17 set write secret lvl=Handshake
[2025-04-30T10:38:27.687823973Z TRACE quiche::tls] 42f896e89a88574ef9274988d4e71a4c78a24e17 write message lvl=Handshake len=9950
[2025-04-30T10:38:27.687829193Z TRACE quiche::tls] 42f896e89a88574ef9274988d4e71a4c78a24e17 set write secret lvl=OneRTT
[2025-04-30T10:38:27.687837128Z TRACE quiche::tls] 42f896e89a88574ef9274988d4e71a4c78a24e17 set read secret lvl=Handshake
[2025-04-30T10:38:27.687857376Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm PADDING len=893
[2025-04-30T10:38:27.687880109Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 1200 bytes
[2025-04-30T10:38:27.687901499Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:27.687914563Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Initial version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=99 pn=0 
[2025-04-30T10:38:27.687918430Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm ACK delay=49 blocks=[0..0] ecn_counts=None
[2025-04-30T10:38:27.687923500Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=0 len=90
[2025-04-30T10:38:27.687933890Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=998.969792ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=154 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 399, tv_nsec: 39459418 }, next_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:27.687965198Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=992 pn=1 
[2025-04-30T10:38:27.687968865Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=0 len=988
[2025-04-30T10:38:27.687975969Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=998.928054ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1200 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 399, tv_nsec: 39459418 }, next_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:27.688027255Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 399, tv_nsec: 39459418 } })
[2025-04-30T10:38:27.688036112Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:27.688044738Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:27.688059727Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=1146 pn=2 
[2025-04-30T10:38:27.688065578Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=988 len=1141
[2025-04-30T10:38:27.688077971Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=998.825541ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 399, tv_nsec: 39604752 }, next_time: Instant { tv_sec: 399, tv_nsec: 39604752 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:27.688138304Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 399, tv_nsec: 39604752 } })
[2025-04-30T10:38:27.688146911Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:27.688154996Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:27.688170104Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=1146 pn=3 
[2025-04-30T10:38:27.688175755Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=2129 len=1141
[2025-04-30T10:38:27.688188489Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=998.715103ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 399, tv_nsec: 39714398 }, next_time: Instant { tv_sec: 399, tv_nsec: 39714398 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:27.688234696Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 399, tv_nsec: 39714398 } })
[2025-04-30T10:38:27.688242099Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:27.688249624Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:27.688256406Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:27.688261356Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:28.688319096Z TRACE quiche_server] timed out
[2025-04-30T10:38:28.688350846Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 loss detection timeout expired
[2025-04-30T10:38:28.688359372Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=997.543829ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 399, tv_nsec: 39714398 }, next_time: Instant { tv_sec: 399, tv_nsec: 39714398 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:28.688390471Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:28.688394308Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.687469464Z TRACE quiche_server] timed out
[2025-04-30T10:38:29.687511012Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 loss detection timeout expired
[2025-04-30T10:38:29.687517875Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=1.996385326s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 399, tv_nsec: 39714398 }, next_time: Instant { tv_sec: 399, tv_nsec: 39714398 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.687547641Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.687551278Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.723890246Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.723908200Z TRACE quiche_server] got packet Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a
[2025-04-30T10:38:29.723922587Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a len=20 pn=8 
[2025-04-30T10:38:29.723943877Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm PING mtu_probe=None
[2025-04-30T10:38:29.723948345Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm PADDING len=2
[2025-04-30T10:38:29.723972181Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 dropped epoch 0 state
[2025-04-30T10:38:29.723976138Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 57 bytes
[2025-04-30T10:38:29.723982770Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.723998250Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=1146 pn=4 
[2025-04-30T10:38:29.724002578Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm ACK delay=9 blocks=[8..8] ecn_counts=None
[2025-04-30T10:38:29.724008349Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=3270 len=1136
[2025-04-30T10:38:29.724020842Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=3.995964282s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4646 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 401, tv_nsec: 75540710 }, next_time: Instant { tv_sec: 401, tv_nsec: 75540710 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.724090383Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 75540710 } })
[2025-04-30T10:38:29.724098458Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.724103678Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.724115069Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=1146 pn=5 
[2025-04-30T10:38:29.724118957Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=4406 len=1141
[2025-04-30T10:38:29.724127723Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=3.995978259s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5846 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 401, tv_nsec: 75661387 }, next_time: Instant { tv_sec: 401, tv_nsec: 75661387 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.724166827Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 75661387 } })
[2025-04-30T10:38:29.724172507Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.724177226Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.724186183Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=1146 pn=6 
[2025-04-30T10:38:29.724189660Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=5547 len=1141
[2025-04-30T10:38:29.724196703Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=3.995982727s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7046 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 401, tv_nsec: 75734815 }, next_time: Instant { tv_sec: 401, tv_nsec: 75734815 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.724230667Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 75734815 } })
[2025-04-30T10:38:29.724241808Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.724246727Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.724255774Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=1146 pn=7 
[2025-04-30T10:38:29.724259331Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=6688 len=1141
[2025-04-30T10:38:29.724266574Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=3.995982447s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8246 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 401, tv_nsec: 75804346 }, next_time: Instant { tv_sec: 401, tv_nsec: 75804346 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.724301490Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 75804346 } })
[2025-04-30T10:38:29.724306780Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.724311369Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.724319314Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=1146 pn=8 
[2025-04-30T10:38:29.724322740Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=7829 len=1141
[2025-04-30T10:38:29.724329543Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=3.99598408s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9446 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 401, tv_nsec: 75868948 }, next_time: Instant { tv_sec: 401, tv_nsec: 75868948 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.724362295Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 75868948 } })
[2025-04-30T10:38:29.724367434Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.724371853Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.724379808Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=985 pn=9 
[2025-04-30T10:38:29.724389075Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=8970 len=980
[2025-04-30T10:38:29.724396769Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=3.995977378s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10485 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 401, tv_nsec: 75929472 }, next_time: Instant { tv_sec: 401, tv_nsec: 75929472 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.724428609Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 1039 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 75929472 } })
[2025-04-30T10:38:29.724432587Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.724436043Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.724439039Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.724441293Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.756098231Z TRACE quiche_server] got 61 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.756113861Z TRACE quiche_server] got packet Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a
[2025-04-30T10:38:29.756123719Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a len=24 pn=9 
[2025-04-30T10:38:29.756131925Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=53 blocks=[1..4] ecn_counts=None
[2025-04-30T10:38:29.756137535Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 1
[2025-04-30T10:38:29.756141302Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 2
[2025-04-30T10:38:29.756144288Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 3
[2025-04-30T10:38:29.756147143Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 4
[2025-04-30T10:38:29.756152754Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm PING mtu_probe=None
[2025-04-30T10:38:29.756169616Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 61 bytes
[2025-04-30T10:38:29.756175477Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.756187549Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=5 pn=10 
[2025-04-30T10:38:29.756191407Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm ACK delay=8 blocks=[9..9] ecn_counts=None
[2025-04-30T10:38:29.756200373Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=64.581763ms latest_rtt=32.13607ms srtt=32.13607ms min_rtt=32.13607ms rttvar=16.068035ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5839 app_limited=true congestion_recovery_start_time=None Rate { delivered: 4646, delivered_time: Instant { tv_sec: 401, tv_nsec: 107676780 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 4, rate_sample: RateSample { delivery_rate: 2246, is_app_limited: true, interval: 2.068217362s, delivered: 4646, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 525110, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.756266237Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 59 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 107733467 } })
[2025-04-30T10:38:29.756273832Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.756279102Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.756283540Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.756286606Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.756806634Z TRACE quiche_server] got 61 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.756818367Z TRACE quiche_server] got packet Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a
[2025-04-30T10:38:29.756825380Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a len=24 pn=10 
[2025-04-30T10:38:29.756831451Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=34 blocks=[1..5] ecn_counts=None
[2025-04-30T10:38:29.756835268Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 5
[2025-04-30T10:38:29.756839025Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm PING mtu_probe=None
[2025-04-30T10:38:29.756850587Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 61 bytes
[2025-04-30T10:38:29.756854735Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.756863601Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=5 pn=11 
[2025-04-30T10:38:29.756866537Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm ACK delay=4 blocks=[9..10] ecn_counts=None
[2025-04-30T10:38:29.756873159Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=48.189375ms latest_rtt=32.717594ms srtt=32.17476ms min_rtt=32.13607ms rttvar=12.128407ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4639 app_limited=true congestion_recovery_start_time=None Rate { delivered: 5846, delivered_time: Instant { tv_sec: 401, tv_nsec: 108378981 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 5, rate_sample: RateSample { delivery_rate: 2826, is_app_limited: true, interval: 2.068217362s, delivered: 5846, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 524479, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.756920989Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 59 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 107733467 } })
[2025-04-30T10:38:29.756926690Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.756930628Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.756933854Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.756936168Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.757691289Z TRACE quiche_server] got 61 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.757695427Z TRACE quiche_server] got packet Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a
[2025-04-30T10:38:29.757700747Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a len=24 pn=11 
[2025-04-30T10:38:29.757706097Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=26 blocks=[1..6] ecn_counts=None
[2025-04-30T10:38:29.757709403Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 6
[2025-04-30T10:38:29.757712619Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm PING mtu_probe=None
[2025-04-30T10:38:29.757743046Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 61 bytes
[2025-04-30T10:38:29.757747194Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.757754568Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=5 pn=12 
[2025-04-30T10:38:29.757757273Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm ACK delay=6 blocks=[9..11] ecn_counts=None
[2025-04-30T10:38:29.757763154Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=36.450379ms latest_rtt=33.519994ms srtt=32.316914ms min_rtt=32.13607ms rttvar=9.380613ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3439 app_limited=true congestion_recovery_start_time=None Rate { delivered: 7046, delivered_time: Instant { tv_sec: 401, tv_nsec: 109254809 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 6, rate_sample: RateSample { delivery_rate: 3406, is_app_limited: true, interval: 2.068217362s, delivered: 7046, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522172, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.757798110Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 59 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 107733467 } })
[2025-04-30T10:38:29.757802919Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.757806556Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.757809602Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.757811816Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.758681833Z TRACE quiche_server] got 61 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.758692843Z TRACE quiche_server] got packet Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a
[2025-04-30T10:38:29.758698144Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a len=24 pn=12 
[2025-04-30T10:38:29.758703063Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=26 blocks=[1..7] ecn_counts=None
[2025-04-30T10:38:29.758706199Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 7
[2025-04-30T10:38:29.758709435Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm PING mtu_probe=None
[2025-04-30T10:38:29.758718542Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 61 bytes
[2025-04-30T10:38:29.758722249Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.758728731Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=5 pn=13 
[2025-04-30T10:38:29.758731406Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm ACK delay=3 blocks=[9..12] ecn_counts=None
[2025-04-30T10:38:29.758737217Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=28.259102ms latest_rtt=34.44785ms srtt=32.55728ms min_rtt=32.13607ms rttvar=7.516193ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2239 app_limited=true congestion_recovery_start_time=None Rate { delivered: 8246, delivered_time: Instant { tv_sec: 401, tv_nsec: 110252196 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 7, rate_sample: RateSample { delivery_rate: 3987, is_app_limited: true, interval: 2.068217362s, delivered: 8246, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518317, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.758770590Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 59 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 107733467 } })
[2025-04-30T10:38:29.758775239Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.758778876Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.758781671Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.758783875Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.759669852Z TRACE quiche_server] got 61 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.759676144Z TRACE quiche_server] got packet Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a
[2025-04-30T10:38:29.759681153Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a len=24 pn=13 
[2025-04-30T10:38:29.759685912Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=28 blocks=[1..8] ecn_counts=None
[2025-04-30T10:38:29.759689018Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 8
[2025-04-30T10:38:29.759692104Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm PING mtu_probe=None
[2025-04-30T10:38:29.759701121Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 61 bytes
[2025-04-30T10:38:29.759709186Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.759716029Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Handshake version=1 dcid=9288183d6a27c02a scid=42f896e89a88574ef9274988d4e71a4c78a24e17 len=5 pn=14 
[2025-04-30T10:38:29.759718564Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm ACK delay=4 blocks=[9..13] ecn_counts=None
[2025-04-30T10:38:29.759724084Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=22.664174ms latest_rtt=35.366318ms srtt=32.880409ms min_rtt=32.13607ms rttvar=6.283403ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1039 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9446, delivered_time: Instant { tv_sec: 401, tv_nsec: 111235266 }, first_sent_time: Instant { tv_sec: 399, tv_nsec: 39459418 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 8, rate_sample: RateSample { delivery_rate: 4567, is_app_limited: true, interval: 2.068217362s, delivered: 9446, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 513223, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.759756214Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 59 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 107733467 } })
[2025-04-30T10:38:29.759760743Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.759764310Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.759767105Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.759769309Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.761876734Z TRACE quiche_server] got 186 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.761885411Z TRACE quiche_server] got packet Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a
[2025-04-30T10:38:29.761892023Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Handshake version=1 dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 scid=9288183d6a27c02a len=63 pn=14 
[2025-04-30T10:38:29.761898535Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=164 blocks=[1..9] ecn_counts=None
[2025-04-30T10:38:29.761902803Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 9
[2025-04-30T10:38:29.761907462Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm CRYPTO off=0 len=36
[2025-04-30T10:38:29.761923563Z TRACE quiche::tls] 42f896e89a88574ef9274988d4e71a4c78a24e17 set read secret lvl=OneRTT
[2025-04-30T10:38:29.761963898Z TRACE quiche::tls] 42f896e89a88574ef9274988d4e71a4c78a24e17 write message lvl=OneRTT len=396
[2025-04-30T10:38:29.761973407Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 dropped epoch 1 state
[2025-04-30T10:38:29.761978055Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 15728640, initial_max_stream_data_bidi_local: 6291456, initial_max_stream_data_bidi_remote: 0, initial_max_stream_data_uni: 32768, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(9288183d6a27c02a), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T10:38:29.762009384Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false len=65 pn=15 
[2025-04-30T10:38:29.762017069Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[63, b2, 7d, a2, 23, a4, da, 10] reset_token=[69, 64, 16, 22, 81, 68, 8b, f4, 68, 32, 31, b8, cb, f8, f3, f8]
[2025-04-30T10:38:29.762024833Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T10:38:29.762034511Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 186 bytes
[2025-04-30T10:38:29.762039190Z DEBUG quiche_apps::common] 42f896e89a88574ef9274988d4e71a4c78a24e17 response stream 0 is writable with capacity Ok(13500)
[2025-04-30T10:38:29.762046834Z TRACE quiche_apps::common] 42f896e89a88574ef9274988d4e71a4c78a24e17 received 17 bytes
[2025-04-30T10:38:29.762050281Z TRACE quiche_apps::common] 42f896e89a88574ef9274988d4e71a4c78a24e17 stream 0 has 17 bytes (fin? true)
[2025-04-30T10:38:29.762055541Z INFO  quiche_apps::common] 42f896e89a88574ef9274988d4e71a4c78a24e17 got GET request for "/www/xktpaffpgy" on stream 0
[2025-04-30T10:38:29.762082482Z INFO  quiche_apps::common] 42f896e89a88574ef9274988d4e71a4c78a24e17 sending response of size 5120 on stream 0
[2025-04-30T10:38:29.762092661Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.762100526Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:38:29.762110274Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Short dcid=9288183d6a27c02a key_phase=false len=1324 pn=15 
[2025-04-30T10:38:29.762114101Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm ACK delay=11 blocks=[15..15] ecn_counts=None
[2025-04-30T10:38:29.762118129Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[32, ca, 3e, 21, e7, e8, 3a, 4e, 28, 28, 67, 4e, 87, b6, b1, 87, 2d, d0, 7b, dd] reset_token=[79, 67, 7b, 80, 0a, 55, 9b, 35, 57, c3, 01, ae, dd, 5d, 9c, 03]
[2025-04-30T10:38:29.762125974Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[9f, 3f, 81, 54, b5, 09, 05, 98, 48, aa, 56, 71, 1f, 40, 7e, 1d, 8e, a3, 12, 39] reset_token=[6a, 3c, c0, 08, 34, d3, eb, 1d, 1f, a7, 3a, c3, 09, 38, 89, fe]
[2025-04-30T10:38:29.762132826Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[d6, 4b, 00, a6, 15, 99, 69, 2c, 3f, c6, cc, e3, 05, a4, f5, c9, f4, 8f, 1a, 9c] reset_token=[5d, de, eb, e3, 1e, 15, 10, 0d, 9d, a2, 1f, f6, b6, eb, 80, a4]
[2025-04-30T10:38:29.762139519Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[74, a0, 90, 63, b3, fa, 85, 3e, a0, 43, 20, 9f, b3, cf, a5, 10, 0b, 0b, 2e, 87] reset_token=[ba, 33, be, 50, 79, 79, 2f, f5, 4f, 11, 6c, ef, 80, b9, 8e, 83]
[2025-04-30T10:38:29.762146061Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[1c, 31, 5d, f8, 89, 26, f9, 2a, b3, 04, 3d, 92, c0, ac, b3, 9d, 43, f4, 2c, 50] reset_token=[06, 8b, 8b, f4, 7e, db, 1b, 48, ec, c3, 4b, e9, 1e, 68, ff, 52]
[2025-04-30T10:38:29.762152644Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[0f, f6, 7a, e4, e5, c4, 93, b6, 75, 87, 1e, 58, 25, 61, a3, 58, 9e, 1c, 97, 15] reset_token=[43, 57, ce, b2, e2, 7a, b0, 10, 2e, 6b, 61, 7a, 06, 3e, 1f, 2a]
[2025-04-30T10:38:29.762159417Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[a0, 5d, d5, bf, f2, 3d, 1a, 7e, 67, 8b, 73, 6e, b3, 69, 9d, d7, 3b, 4c, a0, b2] reset_token=[ef, 52, 61, fa, 7d, bb, 52, d1, 5a, 16, 44, b7, f7, 02, 1c, 3a]
[2025-04-30T10:38:29.762171549Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm HANDSHAKE_DONE
[2025-04-30T10:38:29.762174715Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm CRYPTO off=0 len=396
[2025-04-30T10:38:29.762177851Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm STREAM id=0 off=0 len=633 fin=false
[2025-04-30T10:38:29.762193260Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=80.37208ms latest_rtt=37.516454ms srtt=33.295913ms min_rtt=32.13607ms rttvar=5.543563ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10485, delivered_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 9, rate_sample: RateSample { delivery_rate: 5069, is_app_limited: true, interval: 2.068217362s, delivered: 10485, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 506818, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.762211023Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12150 out_len=1350 left=1324
[2025-04-30T10:38:29.762215432Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Short dcid=9288183d6a27c02a key_phase=false len=1324 pn=16 
[2025-04-30T10:38:29.762218708Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm STREAM id=0 off=633 len=1318 fin=false
[2025-04-30T10:38:29.762225100Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=80.453724ms latest_rtt=37.516454ms srtt=33.295913ms min_rtt=32.13607ms rttvar=5.543563ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2700 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10485, delivered_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 9, rate_sample: RateSample { delivery_rate: 5069, is_app_limited: true, interval: 2.068217362s, delivered: 10485, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 506818, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.762238225Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10800 out_len=1350 left=1324
[2025-04-30T10:38:29.762242102Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Short dcid=9288183d6a27c02a key_phase=false len=1324 pn=17 
[2025-04-30T10:38:29.762245258Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm STREAM id=0 off=1951 len=1318 fin=false
[2025-04-30T10:38:29.762254916Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=80.45172ms latest_rtt=37.516454ms srtt=33.295913ms min_rtt=32.13607ms rttvar=5.543563ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4050 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10485, delivered_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 9, rate_sample: RateSample { delivery_rate: 5069, is_app_limited: true, interval: 2.068217362s, delivered: 10485, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 506818, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.762267580Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9450 out_len=1350 left=1324
[2025-04-30T10:38:29.762271557Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Short dcid=9288183d6a27c02a key_phase=false len=1324 pn=18 
[2025-04-30T10:38:29.762274683Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm STREAM id=0 off=3269 len=1318 fin=false
[2025-04-30T10:38:29.762280334Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=80.455748ms latest_rtt=37.516454ms srtt=33.295913ms min_rtt=32.13607ms rttvar=5.543563ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10485, delivered_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 9, rate_sample: RateSample { delivery_rate: 5069, is_app_limited: true, interval: 2.068217362s, delivered: 10485, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 506818, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.762292387Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8100 out_len=1350 left=1324
[2025-04-30T10:38:29.762296144Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx pkt Short dcid=9288183d6a27c02a key_phase=false len=539 pn=19 
[2025-04-30T10:38:29.762299290Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 tx frm STREAM id=0 off=4587 len=533 fin=true
[2025-04-30T10:38:29.762305080Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 timer=80.456009ms latest_rtt=37.516454ms srtt=33.295913ms min_rtt=32.13607ms rttvar=5.543563ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5965 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10485, delivered_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, end_of_app_limited: 18, last_sent_packet: 19, largest_acked: 9, rate_sample: RateSample { delivery_rate: 5069, is_app_limited: true, interval: 2.068217362s, delivered: 10485, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 399, tv_nsec: 39459418 }), send_elapsed: 0ns, ack_elapsed: 2.068217362s, rtt: 2.068217362s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 506818, last_update: Instant { tv_sec: 401, tv_nsec: 107733467 }, next_time: Instant { tv_sec: 401, tv_nsec: 107733467 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T10:38:29.762410830Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 written 5965 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55560, at: Instant { tv_sec: 401, tv_nsec: 107733467 } })
[2025-04-30T10:38:29.762418313Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.762423353Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.762426950Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.762429895Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.795065094Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.795080022Z TRACE quiche_server] got packet Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false
[2025-04-30T10:38:29.795087817Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false len=23 pn=16 
[2025-04-30T10:38:29.795095271Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=5 blocks=[15..16] ecn_counts=None
[2025-04-30T10:38:29.795100370Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 15
[2025-04-30T10:38:29.795104929Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 16
[2025-04-30T10:38:29.795133793Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 44 bytes
[2025-04-30T10:38:29.795140396Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.795144754Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.795148391Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.797220049Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.797235688Z TRACE quiche_server] got packet Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false
[2025-04-30T10:38:29.797246499Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false len=23 pn=17 
[2025-04-30T10:38:29.797256467Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=6 blocks=[15..18] ecn_counts=None
[2025-04-30T10:38:29.797263751Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 17
[2025-04-30T10:38:29.797269101Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 18
[2025-04-30T10:38:29.797286875Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 44 bytes
[2025-04-30T10:38:29.797296332Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.797302935Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.797307203Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.797804977Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.797819584Z TRACE quiche_server] got packet Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false
[2025-04-30T10:38:29.797837648Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false len=23 pn=18 
[2025-04-30T10:38:29.797861403Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm ACK delay=6 blocks=[15..19] ecn_counts=None
[2025-04-30T10:38:29.797867294Z TRACE quiche::recovery::congestion::recovery] 42f896e89a88574ef9274988d4e71a4c78a24e17 packet newly acked 19
[2025-04-30T10:38:29.797883965Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 44 bytes
[2025-04-30T10:38:29.797891870Z TRACE quiche_server] got 42 bytes from [::ffff:193.167.0.100]:55560 to [::]:443
[2025-04-30T10:38:29.797895256Z TRACE quiche_server] got packet Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false
[2025-04-30T10:38:29.797899665Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx pkt Short dcid=42f896e89a88574ef9274988d4e71a4c78a24e17 key_phase=false len=21 pn=19 
[2025-04-30T10:38:29.797904233Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T10:38:29.797910055Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 processed 42 bytes
[2025-04-30T10:38:29.797913561Z TRACE quiche_server] recv() would block
[2025-04-30T10:38:29.797916006Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.797918290Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.979123006Z TRACE quiche_server] timed out
[2025-04-30T10:38:29.979154435Z TRACE quiche] 42f896e89a88574ef9274988d4e71a4c78a24e17 draining timeout expired
[2025-04-30T10:38:29.979195242Z TRACE quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 done writing
[2025-04-30T10:38:29.979199751Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:38:29.979204249Z INFO  quiche_server] 42f896e89a88574ef9274988d4e71a4c78a24e17 connection collected recv=13 sent=20 lost=0 retrans=0 sent_bytes=16899 recv_bytes=1922 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:55560 validation_state=Validated active=true recv=13 sent=20 lost=0 retrans=0 rtt=35.713108ms min_rtt=Some(32.13607ms) rttvar=6.124491ms cwnd=13500 sent_bytes=16899 recv_bytes=1922 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=153310]