[2025-09-12T02:31:57.563409294Z INFO  quiche_server] listening on [::]:443
[2025-09-12T02:31:57.563430965Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T02:31:57.563437858Z TRACE quiche_server] GSO detected: false
[2025-09-12T02:31:57.901014126Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:55940 to [::]:443
[2025-09-12T02:31:57.901075901Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-09-12T02:31:57.901085309Z WARN  quiche_server] Doing version negotiation
[2025-09-12T02:31:57.901170248Z TRACE quiche_server] recv() would block
[2025-09-12T02:31:59.340616386Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:31:59.340649818Z TRACE quiche_server] got packet Initial version=1 dcid=6f7e359ca9379426be578b scid= token=
[2025-09-12T02:31:59.340666689Z DEBUG quiche_server] New connection: dcid=6f7e359ca9379426be578b scid=d8ed116b073e9c150eebe799b8f36b58b1db69e8
[2025-09-12T02:31:59.340885649Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Initial version=1 dcid=6f7e359ca9379426be578b scid= token= len=1231 pn=4 
[2025-09-12T02:31:59.340900156Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm CRYPTO off=0 len=1210
[2025-09-12T02:31:59.340935451Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 1252 bytes
[2025-09-12T02:31:59.340947224Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:31:59.340951481Z TRACE quiche_server] got packet Initial version=1 dcid=6f7e359ca9379426be578b scid= token=
[2025-09-12T02:31:59.340956842Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Initial version=1 dcid=6f7e359ca9379426be578b scid= token= len=352 pn=5 
[2025-09-12T02:31:59.340961711Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm CRYPTO off=1210 len=330
[2025-09-12T02:31:59.340985845Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-09-12T02:31:59.340990284Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-09-12T02:31:59.341094338Z TRACE quiche::tls] d8ed116b073e9c150eebe799b8f36b58b1db69e8 write message lvl=Initial len=90
[2025-09-12T02:31:59.341127851Z TRACE quiche::tls] d8ed116b073e9c150eebe799b8f36b58b1db69e8 set write secret lvl=Handshake
[2025-09-12T02:31:59.341203853Z TRACE quiche::tls] d8ed116b073e9c150eebe799b8f36b58b1db69e8 write message lvl=Handshake len=700
[2025-09-12T02:31:59.341207419Z TRACE quiche::tls] d8ed116b073e9c150eebe799b8f36b58b1db69e8 set write secret lvl=OneRTT
[2025-09-12T02:31:59.341214913Z TRACE quiche::tls] d8ed116b073e9c150eebe799b8f36b58b1db69e8 set read secret lvl=Handshake
[2025-09-12T02:31:59.341239299Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 dropped invalid packet
[2025-09-12T02:31:59.341242705Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 1252 bytes
[2025-09-12T02:31:59.341258826Z TRACE quiche_server] recv() would block
[2025-09-12T02:31:59.341272521Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Initial version=1 dcid= scid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 len=99 pn=0 
[2025-09-12T02:31:59.341276709Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm ACK delay=39 blocks=[4..5] ecn_counts=None
[2025-09-12T02:31:59.341281909Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm CRYPTO off=0 len=90
[2025-09-12T02:31:59.341292028Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=998.969433ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=146 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, first_sent_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, 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: 1066, tv_nsec: 86751511 }, next_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, 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-12T02:31:59.341324117Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Handshake version=1 dcid= scid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 len=704 pn=1 
[2025-09-12T02:31:59.341327684Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm CRYPTO off=0 len=700
[2025-09-12T02:31:59.341334407Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=998.927434ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=896 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, first_sent_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, 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: 1066, tv_nsec: 86751511 }, next_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, 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-12T02:31:59.341386053Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55718, at: Instant { tv_sec: 1066, tv_nsec: 86751511 } })
[2025-09-12T02:31:59.341396873Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:31:59.341405549Z TRACE quiche_server] recv() would block
[2025-09-12T02:31:59.341412873Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:31:59.341417692Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:31:59.636354545Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:31:59.636387106Z TRACE quiche_server] got packet Initial version=1 dcid=6f7e359ca9379426be578b scid= token=
[2025-09-12T02:31:59.636402235Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Initial version=1 dcid=6f7e359ca9379426be578b scid= token= len=1231 pn=6 
[2025-09-12T02:31:59.636415249Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm CRYPTO off=0 len=1210
[2025-09-12T02:31:59.636443321Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 1252 bytes
[2025-09-12T02:31:59.636454212Z TRACE quiche_server] recv() would block
[2025-09-12T02:31:59.636472155Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Initial version=1 dcid= scid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 len=5 pn=2 
[2025-09-12T02:31:59.636476072Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm ACK delay=8 blocks=[4..6] ecn_counts=None
[2025-09-12T02:31:59.636490108Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=703.771352ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=896 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, first_sent_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, 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: 1066, tv_nsec: 381949973 }, next_time: Instant { tv_sec: 1066, tv_nsec: 381949973 }, 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-12T02:31:59.636591989Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55718, at: Instant { tv_sec: 1066, tv_nsec: 381949973 } })
[2025-09-12T02:31:59.636600214Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:31:59.636605885Z TRACE quiche_server] recv() would block
[2025-09-12T02:31:59.636610624Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:31:59.636613499Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:31:59.637296194Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:31:59.637305201Z TRACE quiche_server] got packet Initial version=1 dcid=6f7e359ca9379426be578b scid= token=
[2025-09-12T02:31:59.637311493Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Initial version=1 dcid=6f7e359ca9379426be578b scid= token= len=352 pn=7 
[2025-09-12T02:31:59.637317314Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm CRYPTO off=1210 len=330
[2025-09-12T02:31:59.637329346Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 dropped invalid packet
[2025-09-12T02:31:59.637332292Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 1252 bytes
[2025-09-12T02:31:59.637336199Z TRACE quiche_server] recv() would block
[2025-09-12T02:31:59.637348352Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Initial version=1 dcid= scid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 len=5 pn=3 
[2025-09-12T02:31:59.637351217Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm ACK delay=4 blocks=[4..7] ecn_counts=None
[2025-09-12T02:31:59.637358871Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=702.902929ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=896 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, first_sent_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, end_of_app_limited: 1, 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: 1066, tv_nsec: 382827813 }, next_time: Instant { tv_sec: 1066, tv_nsec: 382827813 }, 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-12T02:31:59.637393486Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55718, at: Instant { tv_sec: 1066, tv_nsec: 382827813 } })
[2025-09-12T02:31:59.637398636Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:31:59.637402393Z TRACE quiche_server] recv() would block
[2025-09-12T02:31:59.637411109Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:31:59.637413513Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.237452561Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:32:00.237490000Z TRACE quiche_server] got packet Initial version=1 dcid=6f7e359ca9379426be578b scid= token=
[2025-09-12T02:32:00.237504928Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Initial version=1 dcid=6f7e359ca9379426be578b scid= token= len=1231 pn=8 
[2025-09-12T02:32:00.237551245Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm CRYPTO off=0 len=1210
[2025-09-12T02:32:00.237583375Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 1252 bytes
[2025-09-12T02:32:00.237590568Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:00.237604284Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Initial version=1 dcid= scid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 len=5 pn=4 
[2025-09-12T02:32:00.237608361Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm ACK delay=12 blocks=[4..8] ecn_counts=None
[2025-09-12T02:32:00.237623089Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=102.638291ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=896 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, first_sent_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, end_of_app_limited: 1, 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: 1066, tv_nsec: 983083464 }, next_time: Instant { tv_sec: 1066, tv_nsec: 983083464 }, 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-12T02:32:00.237691256Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55718, at: Instant { tv_sec: 1066, tv_nsec: 983083464 } })
[2025-09-12T02:32:00.237698801Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.237704661Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:00.237709080Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:32:00.237712105Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.238375585Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:32:00.238385193Z TRACE quiche_server] got packet Initial version=1 dcid=6f7e359ca9379426be578b scid= token=
[2025-09-12T02:32:00.238391504Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Initial version=1 dcid=6f7e359ca9379426be578b scid= token= len=352 pn=9 
[2025-09-12T02:32:00.238397476Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm CRYPTO off=1210 len=330
[2025-09-12T02:32:00.238409448Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 dropped invalid packet
[2025-09-12T02:32:00.238412163Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 1252 bytes
[2025-09-12T02:32:00.238416070Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:00.238424015Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Initial version=1 dcid= scid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 len=5 pn=5 
[2025-09-12T02:32:00.238426921Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm ACK delay=4 blocks=[4..9] ecn_counts=None
[2025-09-12T02:32:00.238449904Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=101.811787ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=896 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, first_sent_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, end_of_app_limited: 1, 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: 1066, tv_nsec: 983907614 }, next_time: Instant { tv_sec: 1066, tv_nsec: 983907614 }, 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-12T02:32:00.238485580Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55718, at: Instant { tv_sec: 1066, tv_nsec: 983907614 } })
[2025-09-12T02:32:00.238490730Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.238494507Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:00.238497743Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:32:00.238500007Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.340656546Z TRACE quiche_server] timed out
[2025-09-12T02:32:00.340690099Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 loss detection timeout expired
[2025-09-12T02:32:00.340698073Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=998.562906ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=896 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, first_sent_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, end_of_app_limited: 1, 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: 1066, tv_nsec: 983907614 }, next_time: Instant { tv_sec: 1066, tv_nsec: 983907614 }, 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-12T02:32:00.340748678Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Initial version=1 dcid= scid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 len=100 pn=6 
[2025-09-12T02:32:00.340753487Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm ACK delay=12794 blocks=[4..9] ecn_counts=None
[2025-09-12T02:32:00.340759458Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm CRYPTO off=0 len=90
[2025-09-12T02:32:00.340778955Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=998.482776ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1043 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, first_sent_time: Instant { tv_sec: 1066, tv_nsec: 86751511 }, 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: 1067, tv_nsec: 86211388 }, next_time: Instant { tv_sec: 1067, tv_nsec: 86211388 }, 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-12T02:32:00.340872619Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55718, at: Instant { tv_sec: 1067, tv_nsec: 86211388 } })
[2025-09-12T02:32:00.340881236Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.340888609Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:00.340892847Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:32:00.340895773Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.844490004Z TRACE quiche_server] got 105 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:32:00.844556268Z TRACE quiche_server] got packet Initial version=1 dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 scid= token=
[2025-09-12T02:32:00.844572939Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Initial version=1 dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 scid= token= len=23 pn=10 
[2025-09-12T02:32:00.844586324Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm ACK delay=161 blocks=[0..0] ecn_counts=None
[2025-09-12T02:32:00.844594840Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 packet newly acked 0
[2025-09-12T02:32:00.844623193Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Handshake version=1 dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 scid= len=23 pn=0 
[2025-09-12T02:32:00.844628844Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm ACK delay=161 blocks=[1..1] ecn_counts=None
[2025-09-12T02:32:00.844632671Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 packet newly acked 1
[2025-09-12T02:32:00.844645725Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 dropped epoch 0 state
[2025-09-12T02:32:00.844649973Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 105 bytes
[2025-09-12T02:32:00.844657297Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:00.844669900Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:32:00.844673447Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.845034962Z TRACE quiche_server] got 143 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:32:00.845046394Z TRACE quiche_server] got packet Handshake version=1 dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 scid=
[2025-09-12T02:32:00.845055270Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Handshake version=1 dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 scid= len=56 pn=1 
[2025-09-12T02:32:00.845063596Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm CRYPTO off=0 len=36
[2025-09-12T02:32:00.845082491Z TRACE quiche::tls] d8ed116b073e9c150eebe799b8f36b58b1db69e8 set read secret lvl=OneRTT
[2025-09-12T02:32:00.845124269Z TRACE quiche::tls] d8ed116b073e9c150eebe799b8f36b58b1db69e8 write message lvl=OneRTT len=396
[2025-09-12T02:32:00.845133857Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 dropped epoch 1 state
[2025-09-12T02:32:00.845138756Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 4611686018427387903, initial_max_stream_data_bidi_local: 1048576, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 1048576, initial_max_streams_bidi: 16, initial_max_streams_uni: 16, ack_delay_exponent: 3, max_ack_delay: 20, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(), retry_source_connection_id: None, max_datagram_frame_size: Some(1200), unknown_params: None }
[2025-09-12T02:32:00.845181556Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Short dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 key_phase=false len=37 pn=0 
[2025-09-12T02:32:00.845188489Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-09-12T02:32:00.845197907Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 143 bytes
[2025-09-12T02:32:00.845202275Z DEBUG quiche_apps::common] d8ed116b073e9c150eebe799b8f36b58b1db69e8 response stream 0 is writable with capacity Ok(13500)
[2025-09-12T02:32:00.845209879Z TRACE quiche_apps::common] d8ed116b073e9c150eebe799b8f36b58b1db69e8 received 17 bytes
[2025-09-12T02:32:00.845213175Z TRACE quiche_apps::common] d8ed116b073e9c150eebe799b8f36b58b1db69e8 stream 0 has 17 bytes (fin? true)
[2025-09-12T02:32:00.845218656Z INFO  quiche_apps::common] d8ed116b073e9c150eebe799b8f36b58b1db69e8 got GET request for "/www/refgiulgyz" on stream 0
[2025-09-12T02:32:00.845259061Z INFO  quiche_apps::common] d8ed116b073e9c150eebe799b8f36b58b1db69e8 sending response of size 1024 on stream 0
[2025-09-12T02:32:00.845268418Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:00.845285490Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Short dcid= key_phase=false len=1332 pn=7 
[2025-09-12T02:32:00.845290069Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm ACK delay=11 blocks=[0..0] ecn_counts=None
[2025-09-12T02:32:00.845294788Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[70, f2, bd, 93, 43, fa, d2, 5d, 75, c3, 68, c9, 89, 81, c1, bc, 91, ab, da, 29] reset_token=[9c, 7d, 8e, 29, 0d, 2e, e7, f2, 07, af, de, e8, 02, 8f, 67, 8b]
[2025-09-12T02:32:00.845303654Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, 73, 22, 43, 5f, d5, 5e, 62, df, 0e, d0, 68, f8, 17, ea, f3, 5c, 77, d5, 1c] reset_token=[5d, 59, 2e, e7, 54, 29, 92, 8c, 62, 6e, 98, 4f, 23, 8b, 32, 40]
[2025-09-12T02:32:00.845310828Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[95, 8e, eb, 72, 96, c3, 1f, 03, 18, 78, 47, c2, f0, a7, 16, a4, 57, 82, cc, b6] reset_token=[e2, 06, c3, ad, 42, 81, 6f, 61, be, 6e, c3, b9, 8b, 5d, 68, fe]
[2025-09-12T02:32:00.845317570Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[b5, ea, 36, fe, a0, 66, 89, 4c, 03, 93, 5a, 4e, 29, 3b, 5a, 0d, ea, 79, 93, bc] reset_token=[7b, cb, 1c, 7d, bf, ac, 8c, 8d, 09, 69, 73, 52, f0, 1e, b7, 46]
[2025-09-12T02:32:00.845324143Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[0e, d8, eb, e9, a7, ae, 17, 35, 9a, c6, 7e, 3a, 8f, 79, 34, 76, c2, b1, 5f, 9a] reset_token=[fb, 5f, 59, 75, b5, 38, 87, c7, 82, f2, 66, 18, b9, 4f, a9, 5a]
[2025-09-12T02:32:00.845330845Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[1f, 9c, bc, eb, d2, a6, 8a, b5, 67, b7, 24, 5f, 71, 91, 25, 49, 38, 50, 89, d5] reset_token=[8b, 24, ea, c0, d3, 69, 9f, 6d, e1, 57, 45, c1, 02, 1b, 4e, e6]
[2025-09-12T02:32:00.845337538Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[c7, af, 38, a0, 5f, aa, 89, c0, eb, de, cc, fa, 23, 0f, d4, 23, 37, b9, 15, 1d] reset_token=[7d, 04, 09, d4, 2d, 0f, 22, 06, 8e, 8a, e0, f0, b5, b3, 72, 92]
[2025-09-12T02:32:00.845350562Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm HANDSHAKE_DONE
[2025-09-12T02:32:00.845353658Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm CRYPTO off=0 len=396
[2025-09-12T02:32:00.845356844Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm STREAM id=0 off=0 len=641 fin=false
[2025-09-12T02:32:00.845402339Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=3.778195499s latest_rtt=1.50336025s srtt=1.503313288s min_rtt=1.50330658s rttvar=563.753384ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 896, delivered_time: Instant { tv_sec: 1067, tv_nsec: 86211388 }, first_sent_time: Instant { tv_sec: 1067, tv_nsec: 86211388 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { bandwidth: 4.76 Kbps, is_app_limited: true, interval: 1.50336025s, delivered: 896, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1066, tv_nsec: 86751511 }), send_elapsed: 0ns, ack_elapsed: 1.50336025s, rtt: 1.50336025s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 11225, last_update: Instant { tv_sec: 1067, tv_nsec: 86211388 }, next_time: Instant { tv_sec: 1067, tv_nsec: 86211388 }, 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-12T02:32:00.845438055Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx pkt Short dcid= key_phase=false len=389 pn=8 
[2025-09-12T02:32:00.845441722Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 tx frm STREAM id=0 off=641 len=383 fin=true
[2025-09-12T02:32:00.845448615Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 timer=3.778312127s latest_rtt=1.50336025s srtt=1.503313288s min_rtt=1.50330658s rttvar=563.753384ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1757 app_limited=true congestion_recovery_start_time=None Rate { delivered: 896, delivered_time: Instant { tv_sec: 1067, tv_nsec: 86211388 }, first_sent_time: Instant { tv_sec: 1067, tv_nsec: 86211388 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { bandwidth: 4.76 Kbps, is_app_limited: true, interval: 1.50336025s, delivered: 896, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1066, tv_nsec: 86751511 }), send_elapsed: 0ns, ack_elapsed: 1.50336025s, rtt: 1.50336025s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 11225, last_update: Instant { tv_sec: 1067, tv_nsec: 86211388 }, next_time: Instant { tv_sec: 1067, tv_nsec: 86211388 }, 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-12T02:32:00.845547880Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 written 1757 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55718, at: Instant { tv_sec: 1067, tv_nsec: 86211388 } })
[2025-09-12T02:32:00.845561145Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:00.845566896Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:00.845571675Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:32:00.845575212Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:02.349034585Z TRACE quiche_server] got 1352 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:32:02.349085400Z TRACE quiche_server] got packet Short dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 key_phase=false
[2025-09-12T02:32:02.349101009Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Short dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 key_phase=false len=1331 pn=1 
[2025-09-12T02:32:02.349116117Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm ACK delay=60 blocks=[7..7] ecn_counts=None
[2025-09-12T02:32:02.349124873Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 packet newly acked 7
[2025-09-12T02:32:02.349141955Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm PING mtu_probe=None
[2025-09-12T02:32:02.349151513Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm PADDING len=1308
[2025-09-12T02:32:02.349174747Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 1352 bytes
[2025-09-12T02:32:02.349183353Z TRACE quiche_server] got 54 bytes from [::ffff:193.167.0.100]:55718 to [::]:443
[2025-09-12T02:32:02.349187611Z TRACE quiche_server] got packet Short dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 key_phase=false
[2025-09-12T02:32:02.349192700Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx pkt Short dcid=d8ed116b073e9c150eebe799b8f36b58b1db69e8 key_phase=false len=33 pn=2 
[2025-09-12T02:32:02.349198110Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm ACK delay=14 blocks=[7..8] ecn_counts=None
[2025-09-12T02:32:02.349201877Z TRACE quiche::recovery::congestion::recovery] d8ed116b073e9c150eebe799b8f36b58b1db69e8 packet newly acked 8
[2025-09-12T02:32:02.349206957Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 rx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65, 21]
[2025-09-12T02:32:02.349220121Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 processed 54 bytes
[2025-09-12T02:32:02.349225401Z TRACE quiche_server] recv() would block
[2025-09-12T02:32:02.349229128Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:32:02.349232605Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:13.492401170Z TRACE quiche_server] timed out
[2025-09-12T02:32:13.492440934Z TRACE quiche] d8ed116b073e9c150eebe799b8f36b58b1db69e8 draining timeout expired
[2025-09-12T02:32:13.492489585Z TRACE quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 done writing
[2025-09-12T02:32:13.492495075Z TRACE quiche_server] Collecting garbage
[2025-09-12T02:32:13.492501928Z INFO  quiche_server] d8ed116b073e9c150eebe799b8f36b58b1db69e8 connection collected recv=12 sent=9 lost=0 retrans=1 sent_bytes=3008 recv_bytes=6529 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:55718 validation_state=Validated active=true recv=12 sent=9 lost=0 retrans=1 rtt=1.621632505s min_rtt=Some(1.50330658s) rttvar=522.213361ms cwnd=13500 sent_bytes=3008 recv_bytes=6529 lost_bytes=0 stream_retrans_bytes=90 pmtu=1350 delivery_rate=874]