[2025-09-12T02:38:07.180908172Z INFO  quiche_server] listening on [::]:443
[2025-09-12T02:38:07.180927880Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T02:38:07.180933911Z TRACE quiche_server] GSO detected: false
[2025-09-12T02:38:07.493082464Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:39651 to [::]:443
[2025-09-12T02:38:07.493140243Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-09-12T02:38:07.493154620Z WARN  quiche_server] Doing version negotiation
[2025-09-12T02:38:07.493223028Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.201044084Z TRACE quiche_server] got 1280 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:08.201068360Z TRACE quiche_server] got packet Initial version=1 dcid=d3e4554d3974e2940dc9aa299e26 scid= token=
[2025-09-12T02:38:08.201084630Z DEBUG quiche_server] New connection: dcid=d3e4554d3974e2940dc9aa299e26 scid=cdde012873f39c1ff8b118aca8687ab55bd47550
[2025-09-12T02:38:08.201285497Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Initial version=1 dcid=d3e4554d3974e2940dc9aa299e26 scid= token= len=1256 pn=0 
[2025-09-12T02:38:08.201300656Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm CRYPTO off=0 len=63
[2025-09-12T02:38:08.201314431Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm CRYPTO off=67 len=1167
[2025-09-12T02:38:08.201339919Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 1280 bytes
[2025-09-12T02:38:08.201350479Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.201363714Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Initial version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=5 pn=0 
[2025-09-12T02:38:08.201368062Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm ACK delay=12 blocks=[0..0] ecn_counts=None
[2025-09-12T02:38:08.201413176Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=none latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=false congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 858981840 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 858981840 }, end_of_app_limited: 0, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 858981840 }, next_time: Instant { tv_sec: 466, tv_nsec: 858981840 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=None last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.201466777Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 466, tv_nsec: 858981840 } })
[2025-09-12T02:38:08.201474462Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.201479040Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.201483839Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:08.201487616Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.996847572Z TRACE quiche_server] got 1280 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:08.996870886Z TRACE quiche_server] got packet Initial version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= token=
[2025-09-12T02:38:08.996885824Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Initial version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= token= len=1250 pn=7 
[2025-09-12T02:38:08.996916962Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm PADDING len=936
[2025-09-12T02:38:08.996922232Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm CRYPTO off=63 len=4
[2025-09-12T02:38:08.996940016Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm CRYPTO off=1234 len=284
[2025-09-12T02:38:08.996967147Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-09-12T02:38:08.996972366Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-09-12T02:38:08.997060522Z TRACE quiche::tls] cdde012873f39c1ff8b118aca8687ab55bd47550 write message lvl=Initial len=90
[2025-09-12T02:38:08.997087392Z TRACE quiche::tls] cdde012873f39c1ff8b118aca8687ab55bd47550 set write secret lvl=Handshake
[2025-09-12T02:38:08.997176720Z TRACE quiche::tls] cdde012873f39c1ff8b118aca8687ab55bd47550 write message lvl=Handshake len=9948
[2025-09-12T02:38:08.997180988Z TRACE quiche::tls] cdde012873f39c1ff8b118aca8687ab55bd47550 set write secret lvl=OneRTT
[2025-09-12T02:38:08.997187600Z TRACE quiche::tls] cdde012873f39c1ff8b118aca8687ab55bd47550 set read secret lvl=Handshake
[2025-09-12T02:38:08.997211685Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 1280 bytes
[2025-09-12T02:38:08.997221945Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.997233837Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Initial version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=101 pn=1 
[2025-09-12T02:38:08.997237223Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm ACK delay=43 blocks=[0..0, 7..7] ecn_counts=None
[2025-09-12T02:38:08.997241942Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=0 len=90
[2025-09-12T02:38:08.997251300Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=998.972819ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=148 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 467, tv_nsec: 654852444 }, next_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.997271187Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=1006 pn=2 
[2025-09-12T02:38:08.997273892Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=0 len=1002
[2025-09-12T02:38:08.997279533Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=998.944907ms 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: 467, tv_nsec: 654852444 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 467, tv_nsec: 654852444 }, next_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.997322674Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 654852444 } })
[2025-09-12T02:38:08.997331510Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.997337852Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.997349414Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=1154 pn=3 
[2025-09-12T02:38:08.997354142Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=1002 len=1149
[2025-09-12T02:38:08.997363039Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=998.86137ms 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: 467, tv_nsec: 654852444 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 467, tv_nsec: 654969303 }, next_time: Instant { tv_sec: 467, tv_nsec: 654969303 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.997408895Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 654969303 } })
[2025-09-12T02:38:08.997417301Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.997422992Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.997435064Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=1154 pn=4 
[2025-09-12T02:38:08.997439773Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=2151 len=1149
[2025-09-12T02:38:08.997447167Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=998.777232ms 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: 467, tv_nsec: 654852444 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 467, tv_nsec: 655054263 }, next_time: Instant { tv_sec: 467, tv_nsec: 655054263 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.997483515Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 655054263 } })
[2025-09-12T02:38:08.997491110Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.997496810Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.997507931Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=1154 pn=5 
[2025-09-12T02:38:08.997512259Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=3300 len=1149
[2025-09-12T02:38:08.997519423Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=998.705026ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4800 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 467, tv_nsec: 655127921 }, next_time: Instant { tv_sec: 467, tv_nsec: 655127921 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.997548447Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 655127921 } })
[2025-09-12T02:38:08.997555430Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.997560780Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.997570839Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=1154 pn=6 
[2025-09-12T02:38:08.997575488Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=4449 len=1149
[2025-09-12T02:38:08.997583433Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=998.641037ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6000 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 467, tv_nsec: 655191951 }, next_time: Instant { tv_sec: 467, tv_nsec: 655191951 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.997611455Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 655191951 } })
[2025-09-12T02:38:08.997624279Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.997629830Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.997641211Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=1154 pn=7 
[2025-09-12T02:38:08.997644367Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=5598 len=1149
[2025-09-12T02:38:08.997650839Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=998.57364ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7200 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 467, tv_nsec: 655260720 }, next_time: Instant { tv_sec: 467, tv_nsec: 655260720 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.997680334Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 655260720 } })
[2025-09-12T02:38:08.997687187Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.997692447Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.997702997Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=382 pn=8 
[2025-09-12T02:38:08.997707586Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=6747 len=377
[2025-09-12T02:38:08.997714699Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=998.50976ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7628 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654852444 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 467, tv_nsec: 655323678 }, next_time: Instant { tv_sec: 467, tv_nsec: 655323678 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:08.997743473Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 428 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 655323678 } })
[2025-09-12T02:38:08.997750336Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:08.997755466Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:08.997766696Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:08.997770984Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.030161887Z TRACE quiche_server] got 1280 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.030179430Z TRACE quiche_server] got packet Initial version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= token=
[2025-09-12T02:38:09.030190471Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Initial version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= token= len=1250 pn=8 
[2025-09-12T02:38:09.030198526Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[0..1] ecn_counts=None
[2025-09-12T02:38:09.030204127Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 0
[2025-09-12T02:38:09.030207623Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 1
[2025-09-12T02:38:09.030216700Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm PADDING len=1227
[2025-09-12T02:38:09.030236117Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 1280 bytes
[2025-09-12T02:38:09.030246015Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.030252768Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.030261795Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=0 
[2025-09-12T02:38:09.030270521Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..2] ecn_counts=None
[2025-09-12T02:38:09.030276162Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 2
[2025-09-12T02:38:09.030295007Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 dropped epoch 0 state
[2025-09-12T02:38:09.030303573Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.030314954Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.030318591Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.030323591Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=1 
[2025-09-12T02:38:09.030328710Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..3] ecn_counts=None
[2025-09-12T02:38:09.030332217Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 3
[2025-09-12T02:38:09.030340192Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.030344280Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.030354759Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=1154 pn=9 
[2025-09-12T02:38:09.030358306Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=7124 len=1149
[2025-09-12T02:38:09.030366602Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=70.100905ms latest_rtt=32.981421ms srtt=32.973877ms min_rtt=32.963788ms rttvar=9.286739ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6428 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2400, delivered_time: Instant { tv_sec: 467, tv_nsec: 687950724 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654969303 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 3, rate_sample: RateSample { bandwidth: 580.09 Kbps, is_app_limited: true, interval: 33.09828ms, delivered: 2400, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 654852444 }), send_elapsed: 116.859µs, ack_elapsed: 33.09828ms, rtt: 32.981421ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 511768, last_update: Instant { tv_sec: 467, tv_nsec: 687974869 }, next_time: Instant { tv_sec: 467, tv_nsec: 687974869 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.030446812Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 687974869 } })
[2025-09-12T02:38:09.030456921Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.030465276Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.030479603Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=1154 pn=10 
[2025-09-12T02:38:09.030484944Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=8273 len=1149
[2025-09-12T02:38:09.030495624Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=70.094453ms latest_rtt=32.981421ms srtt=32.973877ms min_rtt=32.963788ms rttvar=9.286739ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7628 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2400, delivered_time: Instant { tv_sec: 467, tv_nsec: 687950724 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654969303 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 3, rate_sample: RateSample { bandwidth: 580.09 Kbps, is_app_limited: true, interval: 33.09828ms, delivered: 2400, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 654852444 }), send_elapsed: 116.859µs, ack_elapsed: 33.09828ms, rtt: 32.981421ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 511768, last_update: Instant { tv_sec: 467, tv_nsec: 687974869 }, next_time: Instant { tv_sec: 467, tv_nsec: 687974869 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.030536380Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 687974869 } })
[2025-09-12T02:38:09.030545297Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.030552631Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.030566687Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Handshake version=1 dcid= scid=cdde012873f39c1ff8b118aca8687ab55bd47550 len=531 pn=11 
[2025-09-12T02:38:09.030570915Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm CRYPTO off=9422 len=526
[2025-09-12T02:38:09.030601041Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=70.075758ms latest_rtt=32.981421ms srtt=32.973877ms min_rtt=32.963788ms rttvar=9.286739ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8205 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2400, delivered_time: Instant { tv_sec: 467, tv_nsec: 687950724 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 654969303 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 3, rate_sample: RateSample { bandwidth: 580.09 Kbps, is_app_limited: true, interval: 33.09828ms, delivered: 2400, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 654852444 }), send_elapsed: 116.859µs, ack_elapsed: 33.09828ms, rtt: 32.981421ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 511768, last_update: Instant { tv_sec: 467, tv_nsec: 687974869 }, next_time: Instant { tv_sec: 467, tv_nsec: 687974869 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.030647879Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 577 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 687974869 } })
[2025-09-12T02:38:09.030657798Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.030664681Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.030672015Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.030677615Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.030708042Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.030712580Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.030719574Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=2 
[2025-09-12T02:38:09.030725825Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..4] ecn_counts=None
[2025-09-12T02:38:09.030730083Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 4
[2025-09-12T02:38:09.030742957Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.030747516Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.030751283Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.030754098Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.031661471Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.031667792Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.031672031Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=3 
[2025-09-12T02:38:09.031676118Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..5] ecn_counts=None
[2025-09-12T02:38:09.031679074Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 5
[2025-09-12T02:38:09.031686979Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.031690495Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.031693851Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.031696106Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.032667558Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.032673008Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.032677176Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=4 
[2025-09-12T02:38:09.032681254Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..6] ecn_counts=None
[2025-09-12T02:38:09.032684169Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 6
[2025-09-12T02:38:09.032691693Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.032699949Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.032703475Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.032705790Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.033618983Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.033624584Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.033628771Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=5 
[2025-09-12T02:38:09.033632909Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..7] ecn_counts=None
[2025-09-12T02:38:09.033635744Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 7
[2025-09-12T02:38:09.033643279Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.033646655Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.033649881Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.033652095Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.034001661Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.034005088Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.034009055Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=6 
[2025-09-12T02:38:09.034013042Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..8] ecn_counts=None
[2025-09-12T02:38:09.034016579Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 8
[2025-09-12T02:38:09.034023682Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.034026958Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.034030054Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.034032308Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.061874974Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.061886245Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.061892496Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=7 
[2025-09-12T02:38:09.061898368Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..9] ecn_counts=None
[2025-09-12T02:38:09.061902145Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 9
[2025-09-12T02:38:09.061914017Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.061918135Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.061922964Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.061925478Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.062832220Z TRACE quiche_server] got 52 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.062845004Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.062851726Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=23 pn=8 
[2025-09-12T02:38:09.062858188Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..10] ecn_counts=None
[2025-09-12T02:38:09.062862687Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 10
[2025-09-12T02:38:09.062884568Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 52 bytes
[2025-09-12T02:38:09.062890008Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.062895639Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.062898694Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.063882039Z TRACE quiche_server] got 91 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.063891838Z TRACE quiche_server] got packet Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid=
[2025-09-12T02:38:09.063896877Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Handshake version=1 dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 scid= len=62 pn=9 
[2025-09-12T02:38:09.063901926Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=0 blocks=[2..11] ecn_counts=None
[2025-09-12T02:38:09.063905273Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 11
[2025-09-12T02:38:09.063908860Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm CRYPTO off=0 len=36
[2025-09-12T02:38:09.063919850Z TRACE quiche::tls] cdde012873f39c1ff8b118aca8687ab55bd47550 set read secret lvl=OneRTT
[2025-09-12T02:38:09.063933636Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 dropped epoch 1 state
[2025-09-12T02:38:09.063936962Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 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: 1452, initial_max_data: 786432, initial_max_stream_data_bidi_local: 524288, initial_max_stream_data_bidi_remote: 524288, initial_max_stream_data_uni: 524288, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 26, disable_active_migration: false, active_conn_id_limit: 4, initial_source_connection_id: Some(), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T02:38:09.063955527Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 91 bytes
[2025-09-12T02:38:09.063965857Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.063973922Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Short dcid= key_phase=false len=121 pn=12 
[2025-09-12T02:38:09.063976627Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[1c, ab, 03, 04, 3a, 32, d6, 34, 55, a3, e5, 8f, ad, b4, de, e8, 37, 65, b4, f0] reset_token=[6b, 3a, c0, 2e, a4, bf, b9, f8, 44, 70, 0c, bd, b4, 63, a2, e2]
[2025-09-12T02:38:09.063984281Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[90, 37, 2c, d2, 2c, f6, 52, 03, 76, 81, c9, 4d, 23, 22, a4, 2c, 1c, e9, d4, ee] reset_token=[f3, f0, 1a, 0f, ca, 8c, 6f, 3e, 29, ae, 89, 35, 80, 77, f5, fc]
[2025-09-12T02:38:09.063993949Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[5e, 3f, 29, 16, a2, 91, f1, 0a, 78, 20, c1, 35, 05, 65, 51, 43, 80, c6, 37, c9] reset_token=[a6, 9b, 4c, a6, a5, 63, 41, 62, aa, 34, d2, 97, ac, 15, 8c, c3]
[2025-09-12T02:38:09.064003477Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm HANDSHAKE_DONE
[2025-09-12T02:38:09.064018124Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=68.237738ms latest_rtt=33.548891ms srtt=33.578302ms min_rtt=31.54413ms rttvar=2.177573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=139 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10605, delivered_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 11, rate_sample: RateSample { bandwidth: 1.96 Mbps, is_app_limited: true, interval: 33.573036ms, delivered: 8205, prior_delivered: 2400, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 687950724 }), send_elapsed: 33.005566ms, ack_elapsed: 33.573036ms, rtt: 33.548891ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502556, last_update: Instant { tv_sec: 467, tv_nsec: 721595815 }, next_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.064068890Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 139 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 721595815 } })
[2025-09-12T02:38:09.064074500Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.064079971Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.064084910Z TRACE quiche_server] got packet Short dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 key_phase=false
[2025-09-12T02:38:09.064092765Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Short dcid=cdde012873f39c1ff8b118aca8687ab55bd47550 key_phase=false len=37 pn=0 
[2025-09-12T02:38:09.064100770Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-09-12T02:38:09.064112251Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 58 bytes
[2025-09-12T02:38:09.064116940Z DEBUG quiche_apps::common] cdde012873f39c1ff8b118aca8687ab55bd47550 response stream 0 is writable with capacity Ok(13361)
[2025-09-12T02:38:09.064125937Z TRACE quiche_apps::common] cdde012873f39c1ff8b118aca8687ab55bd47550 received 17 bytes
[2025-09-12T02:38:09.064129844Z TRACE quiche_apps::common] cdde012873f39c1ff8b118aca8687ab55bd47550 stream 0 has 17 bytes (fin? true)
[2025-09-12T02:38:09.064140795Z INFO  quiche_apps::common] cdde012873f39c1ff8b118aca8687ab55bd47550 got GET request for "/www/cjzbfwvsri" on stream 0
[2025-09-12T02:38:09.064164028Z INFO  quiche_apps::common] cdde012873f39c1ff8b118aca8687ab55bd47550 sending response of size 5120 on stream 0
[2025-09-12T02:38:09.064171983Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.064179517Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Short dcid= key_phase=false len=1332 pn=13 
[2025-09-12T02:38:09.064182313Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm ACK delay=10 blocks=[0..0] ecn_counts=None
[2025-09-12T02:38:09.064185438Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm STREAM id=0 off=0 len=1322 fin=false
[2025-09-12T02:38:09.064192993Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=68.269578ms latest_rtt=33.548891ms srtt=33.578302ms min_rtt=31.54413ms rttvar=2.177573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1489 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10605, delivered_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 11, rate_sample: RateSample { bandwidth: 1.96 Mbps, is_app_limited: true, interval: 33.573036ms, delivered: 8205, prior_delivered: 2400, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 687950724 }), send_elapsed: 33.005566ms, ack_elapsed: 33.573036ms, rtt: 33.548891ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502556, last_update: Instant { tv_sec: 467, tv_nsec: 721595815 }, next_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.064212099Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Short dcid= key_phase=false len=1332 pn=14 
[2025-09-12T02:38:09.064214673Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm STREAM id=0 off=1322 len=1326 fin=false
[2025-09-12T02:38:09.064219482Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=68.278886ms latest_rtt=33.548891ms srtt=33.578302ms min_rtt=31.54413ms rttvar=2.177573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2839 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10605, delivered_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 11, rate_sample: RateSample { bandwidth: 1.96 Mbps, is_app_limited: true, interval: 33.573036ms, delivered: 8205, prior_delivered: 2400, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 687950724 }), send_elapsed: 33.005566ms, ack_elapsed: 33.573036ms, rtt: 33.548891ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502556, last_update: Instant { tv_sec: 467, tv_nsec: 721595815 }, next_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.064230633Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Short dcid= key_phase=false len=1332 pn=15 
[2025-09-12T02:38:09.064233098Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm STREAM id=0 off=2648 len=1326 fin=false
[2025-09-12T02:38:09.064237226Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=68.280309ms latest_rtt=33.548891ms srtt=33.578302ms min_rtt=31.54413ms rttvar=2.177573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4189 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10605, delivered_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 11, rate_sample: RateSample { bandwidth: 1.96 Mbps, is_app_limited: true, interval: 33.573036ms, delivered: 8205, prior_delivered: 2400, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 687950724 }), send_elapsed: 33.005566ms, ack_elapsed: 33.573036ms, rtt: 33.548891ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502556, last_update: Instant { tv_sec: 467, tv_nsec: 721595815 }, next_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.064247625Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Short dcid= key_phase=false len=1152 pn=16 
[2025-09-12T02:38:09.064250040Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm STREAM id=0 off=3974 len=1146 fin=true
[2025-09-12T02:38:09.064254859Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=68.279908ms latest_rtt=33.548891ms srtt=33.578302ms min_rtt=31.54413ms rttvar=2.177573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5359 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10605, delivered_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 11, rate_sample: RateSample { bandwidth: 1.96 Mbps, is_app_limited: true, interval: 33.573036ms, delivered: 8205, prior_delivered: 2400, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 687950724 }), send_elapsed: 33.005566ms, ack_elapsed: 33.573036ms, rtt: 33.548891ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502556, last_update: Instant { tv_sec: 467, tv_nsec: 721595815 }, next_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.064344837Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 5220 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 721595815 } })
[2025-09-12T02:38:09.064354676Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.064361048Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.064366157Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.064370356Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.094883062Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.094900044Z TRACE quiche_server] got packet Short dcid=1cab03043a32d63455a3e58fadb4dee83765b4f0 key_phase=false
[2025-09-12T02:38:09.094913058Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Short dcid=1cab03043a32d63455a3e58fadb4dee83765b4f0 key_phase=false len=25 pn=1 
[2025-09-12T02:38:09.094921424Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 path ID 0 now see SCID with seq num 1
[2025-09-12T02:38:09.094927635Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=15 blocks=[12..12] ecn_counts=None
[2025-09-12T02:38:09.094933867Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 12
[2025-09-12T02:38:09.094941081Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-09-12T02:38:09.094958734Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 46 bytes
[2025-09-12T02:38:09.094963883Z INFO  quiche_server] Retiring source CID cdde012873f39c1ff8b118aca8687ab55bd47550
[2025-09-12T02:38:09.094976076Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.094989942Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx pkt Short dcid= key_phase=false len=45 pn=17 
[2025-09-12T02:38:09.094995633Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm ACK delay=9 blocks=[0..1] ecn_counts=None
[2025-09-12T02:38:09.095000542Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=1 conn_id=[7d, b9, 04, 25, ed, e4, 13, d7, f3, 5b, a5, 82, bd, 6c, 0d, 9f, 69, 0b, 7a, 42] reset_token=[e7, e8, 85, f4, 77, 96, ef, a3, db, 2e, a7, 7a, 81, 0a, 01, ec]
[2025-09-12T02:38:09.095042982Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 timer=68.352592ms latest_rtt=30.942863ms srtt=33.248871ms min_rtt=30.942863ms rttvar=2.292038ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5283 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10744, delivered_time: Instant { tv_sec: 467, tv_nsec: 752538678 }, first_sent_time: Instant { tv_sec: 467, tv_nsec: 721595815 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 12, rate_sample: RateSample { bandwidth: 1.96 Mbps, is_app_limited: true, interval: 30.942863ms, delivered: 139, prior_delivered: 10605, prior_time: Some(Instant { tv_sec: 467, tv_nsec: 721595815 }), send_elapsed: 0ns, ack_elapsed: 30.942863ms, rtt: 30.942863ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 507536, last_update: Instant { tv_sec: 467, tv_nsec: 752607828 }, next_time: Instant { tv_sec: 467, tv_nsec: 752607828 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T02:38:09.095104558Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:34862, at: Instant { tv_sec: 467, tv_nsec: 752607828 } })
[2025-09-12T02:38:09.095112923Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.095342644Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.095351942Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.095355488Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.096957744Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.096970979Z TRACE quiche_server] got packet Short dcid=1cab03043a32d63455a3e58fadb4dee83765b4f0 key_phase=false
[2025-09-12T02:38:09.096990065Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Short dcid=1cab03043a32d63455a3e58fadb4dee83765b4f0 key_phase=false len=23 pn=2 
[2025-09-12T02:38:09.096998771Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=6 blocks=[12..14] ecn_counts=None
[2025-09-12T02:38:09.097004592Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 13
[2025-09-12T02:38:09.097008239Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 14
[2025-09-12T02:38:09.097024960Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 44 bytes
[2025-09-12T02:38:09.097038796Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.097043856Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.097047773Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.098973195Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.098997550Z TRACE quiche_server] got packet Short dcid=1cab03043a32d63455a3e58fadb4dee83765b4f0 key_phase=false
[2025-09-12T02:38:09.099006808Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Short dcid=1cab03043a32d63455a3e58fadb4dee83765b4f0 key_phase=false len=23 pn=3 
[2025-09-12T02:38:09.099016546Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm ACK delay=5 blocks=[12..16] ecn_counts=None
[2025-09-12T02:38:09.099021996Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 15
[2025-09-12T02:38:09.099025814Z TRACE quiche::recovery::congestion::recovery] cdde012873f39c1ff8b118aca8687ab55bd47550 packet newly acked 16
[2025-09-12T02:38:09.099041202Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 44 bytes
[2025-09-12T02:38:09.099046953Z TRACE quiche_server] got 42 bytes from [::ffff:193.167.0.100]:34862 to [::]:443
[2025-09-12T02:38:09.099050069Z TRACE quiche_server] got packet Short dcid=1cab03043a32d63455a3e58fadb4dee83765b4f0 key_phase=false
[2025-09-12T02:38:09.099054027Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx pkt Short dcid=1cab03043a32d63455a3e58fadb4dee83765b4f0 key_phase=false len=21 pn=4 
[2025-09-12T02:38:09.099058344Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 rx frm APPLICATION_CLOSE err=0 reason=[]
[2025-09-12T02:38:09.099063865Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 processed 42 bytes
[2025-09-12T02:38:09.099067251Z TRACE quiche_server] recv() would block
[2025-09-12T02:38:09.099070527Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.099082430Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.221235185Z TRACE quiche_server] timed out
[2025-09-12T02:38:09.221261173Z TRACE quiche] cdde012873f39c1ff8b118aca8687ab55bd47550 draining timeout expired
[2025-09-12T02:38:09.221300307Z TRACE quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 done writing
[2025-09-12T02:38:09.221306449Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:38:09.221314183Z INFO  quiche_server] cdde012873f39c1ff8b118aca8687ab55bd47550 connection collected recv=18 sent=18 lost=0 retrans=0 sent_bytes=16079 recv_bytes=4633 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:34862 validation_state=Validated active=true recv=18 sent=18 lost=0 retrans=0 rtt=33.436025ms min_rtt=Some(30.942863ms) rttvar=1.78925ms cwnd=13500 sent_bytes=16079 recv_bytes=4633 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=244392]