[2025-09-12T04:20:56.433974563Z INFO  quiche_server] listening on [::]:443
[2025-09-12T04:20:56.433994681Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T04:20:56.434001423Z TRACE quiche_server] GSO detected: false
[2025-09-12T04:20:56.758912004Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:34253 to [::]:443
[2025-09-12T04:20:56.758959592Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-09-12T04:20:56.758968379Z WARN  quiche_server] Doing version negotiation
[2025-09-12T04:20:56.759215038Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.322831297Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.322864649Z TRACE quiche_server] got packet Initial version=1 dcid=0ad854bf8fce92b8 scid=a566fe8ea7e855bb token=
[2025-09-12T04:20:58.322883094Z DEBUG quiche_server] New connection: dcid=0ad854bf8fce92b8 scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5
[2025-09-12T04:20:58.323104666Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Initial version=1 dcid=0ad854bf8fce92b8 scid=a566fe8ea7e855bb token= len=1174 pn=0 
[2025-09-12T04:20:58.323119524Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm CRYPTO off=0 len=257
[2025-09-12T04:20:58.323149640Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-09-12T04:20:58.323154218Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-09-12T04:20:58.323270305Z TRACE quiche::tls] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 write message lvl=Initial len=123
[2025-09-12T04:20:58.323306071Z TRACE quiche::tls] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 set write secret lvl=Handshake
[2025-09-12T04:20:58.323407490Z TRACE quiche::tls] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 write message lvl=Handshake len=9944
[2025-09-12T04:20:58.323412780Z TRACE quiche::tls] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 set write secret lvl=OneRTT
[2025-09-12T04:20:58.323421246Z TRACE quiche::tls] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 set read secret lvl=Handshake
[2025-09-12T04:20:58.323443798Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm PADDING len=896
[2025-09-12T04:20:58.323463895Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 1200 bytes
[2025-09-12T04:20:58.323474735Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.323489282Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Initial version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=132 pn=0 
[2025-09-12T04:20:58.323493450Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm ACK delay=50 blocks=[0..0] ecn_counts=None
[2025-09-12T04:20:58.323499101Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=0 len=123
[2025-09-12T04:20:58.323509961Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.96759ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 401, tv_nsec: 254928773 }, next_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.323549044Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=959 pn=1 
[2025-09-12T04:20:58.323588517Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=0 len=955
[2025-09-12T04:20:58.323597383Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.880347ms 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: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, 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: 401, tv_nsec: 254928773 }, next_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.323646956Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 254928773 } })
[2025-09-12T04:20:58.323654039Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.323661693Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.323678515Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1146 pn=2 
[2025-09-12T04:20:58.323684265Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=955 len=1141
[2025-09-12T04:20:58.323697240Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.77999ms 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: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, 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: 401, tv_nsec: 255117054 }, next_time: Instant { tv_sec: 401, tv_nsec: 255117054 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.323750428Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 255117054 } })
[2025-09-12T04:20:58.323759445Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.323767220Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.323784502Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1146 pn=3 
[2025-09-12T04:20:58.323790463Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=2096 len=1141
[2025-09-12T04:20:58.323812304Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.664756ms 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: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, 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: 401, tv_nsec: 255222600 }, next_time: Instant { tv_sec: 401, tv_nsec: 255222600 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.323854192Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 255222600 } })
[2025-09-12T04:20:58.323860012Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.323864721Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.323870542Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.323875832Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.769461501Z TRACE quiche_server] got 56 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.769485285Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.769500183Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb len=20 pn=5 
[2025-09-12T04:20:58.769510913Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm PING mtu_probe=None
[2025-09-12T04:20:58.769514930Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm PADDING len=2
[2025-09-12T04:20:58.769537011Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 dropped epoch 0 state
[2025-09-12T04:20:58.769540518Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 56 bytes
[2025-09-12T04:20:58.769546559Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.769585241Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1146 pn=4 
[2025-09-12T04:20:58.769591503Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm ACK delay=10 blocks=[5..5] ecn_counts=None
[2025-09-12T04:20:58.769596673Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=3237 len=1136
[2025-09-12T04:20:58.769608214Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.940519ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4613 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, 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: 401, tv_nsec: 700999876 }, next_time: Instant { tv_sec: 401, tv_nsec: 700999876 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.769660661Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 700999876 } })
[2025-09-12T04:20:58.769667093Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.769671622Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.769683454Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1146 pn=5 
[2025-09-12T04:20:58.769688423Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=4373 len=1141
[2025-09-12T04:20:58.769700676Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.9729ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5813 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, 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: 401, tv_nsec: 701124879 }, next_time: Instant { tv_sec: 401, tv_nsec: 701124879 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.769745940Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 701124879 } })
[2025-09-12T04:20:58.769755007Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.769761199Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.769775195Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1146 pn=6 
[2025-09-12T04:20:58.769780455Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=5514 len=1141
[2025-09-12T04:20:58.769791014Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.972679ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7013 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, 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: 401, tv_nsec: 701215056 }, next_time: Instant { tv_sec: 401, tv_nsec: 701215056 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.769845646Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 701215056 } })
[2025-09-12T04:20:58.769850705Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.769854562Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.769867487Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1146 pn=7 
[2025-09-12T04:20:58.769871975Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=6655 len=1141
[2025-09-12T04:20:58.769882244Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.975044ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8213 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, 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: 401, tv_nsec: 701308731 }, next_time: Instant { tv_sec: 401, tv_nsec: 701308731 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.769920596Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 701308731 } })
[2025-09-12T04:20:58.769925254Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.769930784Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.769944430Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1146 pn=8 
[2025-09-12T04:20:58.769949539Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=7796 len=1141
[2025-09-12T04:20:58.769959769Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.974192ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9413 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, 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: 401, tv_nsec: 701385183 }, next_time: Instant { tv_sec: 401, tv_nsec: 701385183 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.769995725Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 701385183 } })
[2025-09-12T04:20:58.770006596Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.770012817Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.770026092Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1012 pn=9 
[2025-09-12T04:20:58.770030750Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=8937 len=1007
[2025-09-12T04:20:58.770041701Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=998.974022ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10479 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 254928773 }, end_of_app_limited: 8, last_sent_packet: 9, 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: 401, tv_nsec: 701467116 }, next_time: Instant { tv_sec: 401, tv_nsec: 701467116 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.770077127Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1066 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 701467116 } })
[2025-09-12T04:20:58.770081645Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.770087095Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.770093477Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.770097254Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.805284186Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.805301298Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.805314602Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb len=22 pn=6 
[2025-09-12T04:20:58.805325974Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=0 blocks=[1..4] ecn_counts=None
[2025-09-12T04:20:58.805332796Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 1
[2025-09-12T04:20:58.805337976Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 2
[2025-09-12T04:20:58.805341753Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 3
[2025-09-12T04:20:58.805344979Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 4
[2025-09-12T04:20:58.805371088Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 58 bytes
[2025-09-12T04:20:58.805379103Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.805387809Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.805391726Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.806432089Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.806444722Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.806458728Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb len=22 pn=7 
[2025-09-12T04:20:58.806464870Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=0 blocks=[1..5] ecn_counts=None
[2025-09-12T04:20:58.806468667Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 5
[2025-09-12T04:20:58.806481441Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 58 bytes
[2025-09-12T04:20:58.806486520Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.806489676Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.806493754Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb len=22 pn=8 
[2025-09-12T04:20:58.806497761Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=0 blocks=[1..6] ecn_counts=None
[2025-09-12T04:20:58.806500466Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 6
[2025-09-12T04:20:58.806507099Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 58 bytes
[2025-09-12T04:20:58.806510515Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.806515304Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.806517859Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.806815292Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.806828527Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.806837674Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb len=22 pn=9 
[2025-09-12T04:20:58.806845869Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=0 blocks=[1..7] ecn_counts=None
[2025-09-12T04:20:58.806851209Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 7
[2025-09-12T04:20:58.806888970Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 58 bytes
[2025-09-12T04:20:58.806893618Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.806898538Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.806901072Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.807368346Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.807379126Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.807388855Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb len=22 pn=10 
[2025-09-12T04:20:58.807397731Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=0 blocks=[1..8] ecn_counts=None
[2025-09-12T04:20:58.807403321Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 8
[2025-09-12T04:20:58.807420674Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 58 bytes
[2025-09-12T04:20:58.807427156Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.807434139Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.807438387Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.832515706Z TRACE quiche_server] timed out
[2025-09-12T04:20:58.832529642Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 loss detection timeout expired
[2025-09-12T04:20:58.832544479Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=61.252035ms latest_rtt=37.45262ms srtt=36.284349ms min_rtt=35.762002ms rttvar=6.401538ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1066 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 401, tv_nsec: 738837803 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 701385183 }, end_of_app_limited: 9, last_sent_packet: 9, largest_acked: 8, rate_sample: RateSample { bandwidth: 155.62 Kbps, is_app_limited: true, interval: 483.90903ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 401, tv_nsec: 254928773 }), send_elapsed: 446.45641ms, ack_elapsed: 483.90903ms, rtt: 37.45262ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 401, tv_nsec: 701467116 }, next_time: Instant { tv_sec: 401, tv_nsec: 701467116 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.832605633Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Handshake version=1 dcid=a566fe8ea7e855bb scid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 len=1018 pn=10 
[2025-09-12T04:20:58.832611224Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm ACK delay=3151 blocks=[6..10] ecn_counts=None
[2025-09-12T04:20:58.832616604Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=8937 len=1007
[2025-09-12T04:20:58.832630089Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=123.738403ms latest_rtt=37.45262ms srtt=36.284349ms min_rtt=35.762002ms rttvar=6.401538ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2138 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 401, tv_nsec: 738837803 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 701385183 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { bandwidth: 155.62 Kbps, is_app_limited: true, interval: 483.90903ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 401, tv_nsec: 254928773 }), send_elapsed: 446.45641ms, ack_elapsed: 483.90903ms, rtt: 37.45262ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 465076, last_update: Instant { tv_sec: 401, tv_nsec: 764038622 }, next_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.832679070Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 1072 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 764038622 } })
[2025-09-12T04:20:58.832687626Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.832693026Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.832699218Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.832702714Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.843390569Z TRACE quiche_server] got 97 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.843406238Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.843417609Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb len=61 pn=11 
[2025-09-12T04:20:58.843435452Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm CRYPTO off=0 len=36
[2025-09-12T04:20:58.843455299Z TRACE quiche::tls] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 set read secret lvl=OneRTT
[2025-09-12T04:20:58.843501736Z TRACE quiche::tls] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 write message lvl=OneRTT len=396
[2025-09-12T04:20:58.843514039Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 dropped epoch 1 state
[2025-09-12T04:20:58.843519368Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("P-256") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 60000, stateless_reset_token: None, max_udp_payload_size: 1500, initial_max_data: 2500000, initial_max_stream_data_bidi_local: 250000, initial_max_stream_data_bidi_remote: 250000, initial_max_stream_data_uni: 250000, initial_max_streams_bidi: 3, initial_max_streams_uni: 3, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 2, initial_source_connection_id: Some(a566fe8ea7e855bb), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T04:20:58.843531622Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=0 blocks=[1..8] ecn_counts=None
[2025-09-12T04:20:58.843577226Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 97 bytes
[2025-09-12T04:20:58.843589279Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.843603405Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Short dcid=a566fe8ea7e855bb key_phase=false len=441 pn=11 
[2025-09-12T04:20:58.843607813Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[df, 7e, 55, ce, 1e, db, 2b, a8, 6a, 25, 94, 96, 79, b4, a2, 62, c0, 9d, c3, 78] reset_token=[0c, ba, 2c, 5b, 46, 01, 4a, 86, 27, 51, 58, 12, cd, bf, fb, 24]
[2025-09-12T04:20:58.843618513Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm HANDSHAKE_DONE
[2025-09-12T04:20:58.843622020Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm CRYPTO off=0 len=396
[2025-09-12T04:20:58.843636136Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=173.73718ms latest_rtt=37.45262ms srtt=36.284349ms min_rtt=35.762002ms rttvar=6.401538ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=467 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 8, rate_sample: RateSample { bandwidth: 155.62 Kbps, is_app_limited: true, interval: 483.90903ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 401, tv_nsec: 254928773 }), send_elapsed: 446.45641ms, ack_elapsed: 483.90903ms, rtt: 37.45262ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 465076, last_update: Instant { tv_sec: 401, tv_nsec: 764038622 }, next_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.843756620Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 467 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 764038622 } })
[2025-09-12T04:20:58.843766428Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.843772630Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.843784041Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.843787738Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.848783119Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.848798337Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.848808286Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 dropped invalid packet
[2025-09-12T04:20:58.848812534Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 58 bytes
[2025-09-12T04:20:58.848818675Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.848823634Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.848827231Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.871191639Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.871212458Z TRACE quiche_server] got packet Handshake version=1 dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 scid=a566fe8ea7e855bb
[2025-09-12T04:20:58.871224240Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 dropped invalid packet
[2025-09-12T04:20:58.871227907Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 58 bytes
[2025-09-12T04:20:58.871234760Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.871240591Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.871243857Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.875999070Z TRACE quiche_server] got 59 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.876006674Z TRACE quiche_server] got packet Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false
[2025-09-12T04:20:58.876013486Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false len=38 pn=0 
[2025-09-12T04:20:58.876023335Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-09-12T04:20:58.876041208Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 59 bytes
[2025-09-12T04:20:58.876044675Z DEBUG quiche_apps::common] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 response stream 0 is writable with capacity Ok(13033)
[2025-09-12T04:20:58.876052619Z TRACE quiche_apps::common] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 received 17 bytes
[2025-09-12T04:20:58.876056096Z TRACE quiche_apps::common] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 stream 0 has 17 bytes (fin? true)
[2025-09-12T04:20:58.876061686Z INFO  quiche_apps::common] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 got GET request for "/www/lhbhfjyovp" on stream 0
[2025-09-12T04:20:58.876090830Z INFO  quiche_apps::common] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 sending response of size 5120 on stream 0
[2025-09-12T04:20:58.876099417Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.876112751Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Short dcid=a566fe8ea7e855bb key_phase=false len=1324 pn=12 
[2025-09-12T04:20:58.876116568Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm ACK delay=11 blocks=[0..0] ecn_counts=None
[2025-09-12T04:20:58.876122039Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm STREAM id=0 off=0 len=1314 fin=false
[2025-09-12T04:20:58.876133490Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=173.749183ms latest_rtt=37.45262ms srtt=36.284349ms min_rtt=35.762002ms rttvar=6.401538ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1817 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 8, rate_sample: RateSample { bandwidth: 155.62 Kbps, is_app_limited: true, interval: 483.90903ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 401, tv_nsec: 254928773 }), send_elapsed: 446.45641ms, ack_elapsed: 483.90903ms, rtt: 37.45262ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 465076, last_update: Instant { tv_sec: 401, tv_nsec: 807552833 }, next_time: Instant { tv_sec: 401, tv_nsec: 807552833 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.876168024Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Short dcid=a566fe8ea7e855bb key_phase=false len=1324 pn=13 
[2025-09-12T04:20:58.876171591Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm STREAM id=0 off=1314 len=1318 fin=false
[2025-09-12T04:20:58.876178163Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=173.766966ms latest_rtt=37.45262ms srtt=36.284349ms min_rtt=35.762002ms rttvar=6.401538ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3167 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 8, rate_sample: RateSample { bandwidth: 155.62 Kbps, is_app_limited: true, interval: 483.90903ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 401, tv_nsec: 254928773 }), send_elapsed: 446.45641ms, ack_elapsed: 483.90903ms, rtt: 37.45262ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 465076, last_update: Instant { tv_sec: 401, tv_nsec: 807552833 }, next_time: Instant { tv_sec: 401, tv_nsec: 807552833 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.876192399Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Short dcid=a566fe8ea7e855bb key_phase=false len=1324 pn=14 
[2025-09-12T04:20:58.876195565Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm STREAM id=0 off=2632 len=1318 fin=false
[2025-09-12T04:20:58.876200975Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=173.770062ms latest_rtt=37.45262ms srtt=36.284349ms min_rtt=35.762002ms rttvar=6.401538ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4517 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 8, rate_sample: RateSample { bandwidth: 155.62 Kbps, is_app_limited: true, interval: 483.90903ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 401, tv_nsec: 254928773 }), send_elapsed: 446.45641ms, ack_elapsed: 483.90903ms, rtt: 37.45262ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 465076, last_update: Instant { tv_sec: 401, tv_nsec: 807552833 }, next_time: Instant { tv_sec: 401, tv_nsec: 807552833 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.876214170Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx pkt Short dcid=a566fe8ea7e855bb key_phase=false len=1176 pn=15 
[2025-09-12T04:20:58.876221233Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 tx frm STREAM id=0 off=3950 len=1170 fin=true
[2025-09-12T04:20:58.876227976Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 timer=173.764932ms latest_rtt=37.45262ms srtt=36.284349ms min_rtt=35.762002ms rttvar=6.401538ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5719 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, first_sent_time: Instant { tv_sec: 401, tv_nsec: 764038622 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 8, rate_sample: RateSample { bandwidth: 155.62 Kbps, is_app_limited: true, interval: 483.90903ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 401, tv_nsec: 254928773 }), send_elapsed: 446.45641ms, ack_elapsed: 483.90903ms, rtt: 37.45262ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 465076, last_update: Instant { tv_sec: 401, tv_nsec: 807552833 }, next_time: Instant { tv_sec: 401, tv_nsec: 807552833 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T04:20:58.876305851Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 written 5252 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:39949, at: Instant { tv_sec: 401, tv_nsec: 807552833 } })
[2025-09-12T04:20:58.876312654Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.876317212Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.876321059Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.876323824Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.899695061Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.899709378Z TRACE quiche_server] got packet Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false
[2025-09-12T04:20:58.899718074Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false len=23 pn=1 
[2025-09-12T04:20:58.899727432Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=2375 blocks=[11..11] ecn_counts=None
[2025-09-12T04:20:58.899733633Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 11
[2025-09-12T04:20:58.899750154Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 44 bytes
[2025-09-12T04:20:58.899756386Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.899760944Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.899764410Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.909733691Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.909745734Z TRACE quiche_server] got packet Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false
[2025-09-12T04:20:58.909752276Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false len=22 pn=2 
[2025-09-12T04:20:58.909758517Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=0 blocks=[11..13] ecn_counts=None
[2025-09-12T04:20:58.909762846Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 12
[2025-09-12T04:20:58.909765541Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 13
[2025-09-12T04:20:58.909777653Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 43 bytes
[2025-09-12T04:20:58.909788994Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.909792731Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.909795236Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.911512703Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.911519546Z TRACE quiche_server] got packet Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false
[2025-09-12T04:20:58.911524275Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false len=23 pn=3 
[2025-09-12T04:20:58.911528984Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=125 blocks=[11..15] ecn_counts=None
[2025-09-12T04:20:58.911532150Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 14
[2025-09-12T04:20:58.911534564Z TRACE quiche::recovery::congestion::recovery] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 packet newly acked 15
[2025-09-12T04:20:58.911545064Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 44 bytes
[2025-09-12T04:20:58.911548791Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.911572435Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.911577965Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:58.924098626Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:39949 to [::]:443
[2025-09-12T04:20:58.924107623Z TRACE quiche_server] got packet Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false
[2025-09-12T04:20:58.924113694Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx pkt Short dcid=7578d1deb66180dd86d6117bf5f5e8cccd0770d5 key_phase=false len=26 pn=4 
[2025-09-12T04:20:58.924120086Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-09-12T04:20:58.924124494Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 rx frm ACK delay=0 blocks=[11..15] ecn_counts=None
[2025-09-12T04:20:58.924134282Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 processed 47 bytes
[2025-09-12T04:20:58.924138951Z TRACE quiche_server] recv() would block
[2025-09-12T04:20:58.924141897Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:58.924144672Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:59.105378065Z TRACE quiche_server] timed out
[2025-09-12T04:20:59.105398693Z TRACE quiche] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 draining timeout expired
[2025-09-12T04:20:59.105444168Z TRACE quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 done writing
[2025-09-12T04:20:59.105447975Z TRACE quiche_server] Collecting garbage
[2025-09-12T04:20:59.105453124Z INFO  quiche_server] 7578d1deb66180dd86d6117bf5f5e8cccd0770d5 connection collected recv=13 sent=16 lost=0 retrans=1 sent_bytes=17457 recv_bytes=1880 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:39949 validation_state=Validated active=true recv=13 sent=16 lost=0 retrans=1 rtt=36.896716ms min_rtt=Some(33.648832ms) rttvar=5.845273ms cwnd=13500 sent_bytes=17457 recv_bytes=1880 lost_bytes=0 stream_retrans_bytes=1007 pmtu=1350 delivery_rate=72451]