[2025-04-30T12:43:00.172248962Z INFO  quiche_server] listening on [::]:443
[2025-04-30T12:43:00.172269851Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T12:43:00.172276373Z TRACE quiche_server] GSO detected: false
[2025-04-30T12:43:00.986383452Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:34887 to [::]:443
[2025-04-30T12:43:00.986407316Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T12:43:00.986416143Z WARN  quiche_server] Doing version negotiation
[2025-04-30T12:43:00.986498406Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:34887 to [::]:443
[2025-04-30T12:43:00.986509257Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T12:43:00.986514937Z WARN  quiche_server] Doing version negotiation
[2025-04-30T12:43:00.986546406Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:34887 to [::]:443
[2025-04-30T12:43:00.986553179Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T12:43:00.986558048Z WARN  quiche_server] Doing version negotiation
[2025-04-30T12:43:00.986581621Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.211940957Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.211962167Z TRACE quiche_server] got packet Initial version=1 dcid=5362634f8c1c8549 scid=525587b2f6fdef75 token=
[2025-04-30T12:43:01.211970061Z DEBUG quiche_server] New connection: dcid=5362634f8c1c8549 scid=dfc364605f4ee21f080412554b31e43735dd890a
[2025-04-30T12:43:01.212162130Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Initial version=1 dcid=5362634f8c1c8549 scid=525587b2f6fdef75 token= len=1174 pn=0 
[2025-04-30T12:43:01.212174984Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm CRYPTO off=0 len=305
[2025-04-30T12:43:01.212238092Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T12:43:01.212244213Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T12:43:01.212359508Z TRACE quiche::tls] dfc364605f4ee21f080412554b31e43735dd890a write message lvl=Initial len=123
[2025-04-30T12:43:01.212389284Z TRACE quiche::tls] dfc364605f4ee21f080412554b31e43735dd890a set write secret lvl=Handshake
[2025-04-30T12:43:01.212487998Z TRACE quiche::tls] dfc364605f4ee21f080412554b31e43735dd890a write message lvl=Handshake len=9942
[2025-04-30T12:43:01.212492797Z TRACE quiche::tls] dfc364605f4ee21f080412554b31e43735dd890a set write secret lvl=OneRTT
[2025-04-30T12:43:01.212501062Z TRACE quiche::tls] dfc364605f4ee21f080412554b31e43735dd890a set read secret lvl=Handshake
[2025-04-30T12:43:01.212523083Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm PADDING len=845
[2025-04-30T12:43:01.212545505Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 1200 bytes
[2025-04-30T12:43:01.212566585Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.212579398Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Initial version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=132 pn=0 
[2025-04-30T12:43:01.212583346Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm ACK delay=54 blocks=[0..0] ecn_counts=None
[2025-04-30T12:43:01.212588325Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=0 len=123
[2025-04-30T12:43:01.212598975Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.969753ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 11384529 }, next_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.212630935Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=959 pn=1 
[2025-04-30T12:43:01.212634621Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=0 len=955
[2025-04-30T12:43:01.212641494Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.927575ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1200 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 11384529 }, next_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.212691447Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 11384529 } })
[2025-04-30T12:43:01.212699563Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.212708439Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.212723086Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=1146 pn=2 
[2025-04-30T12:43:01.212728507Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=955 len=1141
[2025-04-30T12:43:01.212741180Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.827428ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=2400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 11527917 }, next_time: Instant { tv_sec: 569, tv_nsec: 11527917 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.212808556Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 11527917 } })
[2025-04-30T12:43:01.212827381Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.212835677Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.212867235Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=1146 pn=3 
[2025-04-30T12:43:01.212874339Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=2096 len=1141
[2025-04-30T12:43:01.212887153Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.681385ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=3600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 11654844 }, next_time: Instant { tv_sec: 569, tv_nsec: 11654844 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.212938198Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 11654844 } })
[2025-04-30T12:43:01.212944259Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.212949319Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.212952885Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.212956632Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.588448892Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.588463499Z TRACE quiche_server] got packet Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75
[2025-04-30T12:43:01.588475060Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75 len=21 pn=5 
[2025-04-30T12:43:01.588485390Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm PING mtu_probe=None
[2025-04-30T12:43:01.588503584Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a dropped epoch 0 state
[2025-04-30T12:43:01.588508253Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 58 bytes
[2025-04-30T12:43:01.588515566Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.588532498Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=1146 pn=4 
[2025-04-30T12:43:01.588537407Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm ACK delay=6 blocks=[5..5] ecn_counts=None
[2025-04-30T12:43:01.588542997Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=3237 len=1136
[2025-04-30T12:43:01.588555911Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.96274ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=4613 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 387334643 }, next_time: Instant { tv_sec: 569, tv_nsec: 387334643 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.588622225Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 387334643 } })
[2025-04-30T12:43:01.588632484Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.588638576Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.588652091Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=1146 pn=5 
[2025-04-30T12:43:01.588655147Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=4373 len=1141
[2025-04-30T12:43:01.588663512Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.977999ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=5813 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 387456971 }, next_time: Instant { tv_sec: 569, tv_nsec: 387456971 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.588700381Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 387456971 } })
[2025-04-30T12:43:01.588706252Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.588710700Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.588719907Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=1146 pn=6 
[2025-04-30T12:43:01.588723494Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=5514 len=1141
[2025-04-30T12:43:01.588730607Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.982287ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=7013 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 387528375 }, next_time: Instant { tv_sec: 569, tv_nsec: 387528375 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.588769410Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 387528375 } })
[2025-04-30T12:43:01.588774860Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.588779208Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.588791651Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=1146 pn=7 
[2025-04-30T12:43:01.588797462Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=6655 len=1141
[2025-04-30T12:43:01.588809254Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.972389ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=8213 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 387597524 }, next_time: Instant { tv_sec: 569, tv_nsec: 387597524 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.588860580Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 387597524 } })
[2025-04-30T12:43:01.588867042Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.588871881Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.588887510Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=1146 pn=8 
[2025-04-30T12:43:01.588892780Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=7796 len=1141
[2025-04-30T12:43:01.588905995Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.968992ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=9413 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 387691028 }, next_time: Instant { tv_sec: 569, tv_nsec: 387691028 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.588959114Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 387691028 } })
[2025-04-30T12:43:01.588966317Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.588973441Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.588988769Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Handshake version=1 dcid=525587b2f6fdef75 scid=dfc364605f4ee21f080412554b31e43735dd890a len=1010 pn=9 
[2025-04-30T12:43:01.588994570Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=8937 len=1005
[2025-04-30T12:43:01.589007785Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=998.969192ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=10477 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 11384529 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 569, tv_nsec: 387793138 }, next_time: Instant { tv_sec: 569, tv_nsec: 387793138 }, max_datagram_size: 1200, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.589053310Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 1064 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 387793138 } })
[2025-04-30T12:43:01.589062046Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.589069159Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.589075922Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.589080861Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.620246828Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.620259372Z TRACE quiche_server] got packet Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75
[2025-04-30T12:43:01.620267166Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75 len=25 pn=6 
[2025-04-30T12:43:01.620274430Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm ACK delay=28 blocks=[1..4] ecn_counts=None
[2025-04-30T12:43:01.620279810Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 1
[2025-04-30T12:43:01.620283226Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 2
[2025-04-30T12:43:01.620285991Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 3
[2025-04-30T12:43:01.620288666Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 4
[2025-04-30T12:43:01.620305808Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 62 bytes
[2025-04-30T12:43:01.620317219Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.620322670Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.620325615Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.621070124Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.621074132Z TRACE quiche_server] got packet Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75
[2025-04-30T12:43:01.621079041Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75 len=25 pn=7 
[2025-04-30T12:43:01.621083780Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm ACK delay=15 blocks=[1..5] ecn_counts=None
[2025-04-30T12:43:01.621086936Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 5
[2025-04-30T12:43:01.621095993Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 62 bytes
[2025-04-30T12:43:01.621099419Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.621102485Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.621104709Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.622046336Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.622053079Z TRACE quiche_server] got packet Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75
[2025-04-30T12:43:01.622058208Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75 len=25 pn=8 
[2025-04-30T12:43:01.622063037Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm ACK delay=14 blocks=[1..6] ecn_counts=None
[2025-04-30T12:43:01.622066083Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 6
[2025-04-30T12:43:01.622074809Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 62 bytes
[2025-04-30T12:43:01.622078516Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.622081903Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.622084137Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.623028850Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.623034330Z TRACE quiche_server] got packet Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75
[2025-04-30T12:43:01.623039059Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75 len=25 pn=9 
[2025-04-30T12:43:01.623043557Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm ACK delay=15 blocks=[1..7] ecn_counts=None
[2025-04-30T12:43:01.623046402Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 7
[2025-04-30T12:43:01.623054006Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 62 bytes
[2025-04-30T12:43:01.623057483Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.623060479Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.623062713Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.624010331Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.624015801Z TRACE quiche_server] got packet Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75
[2025-04-30T12:43:01.624020320Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75 len=25 pn=10 
[2025-04-30T12:43:01.624024868Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm ACK delay=15 blocks=[1..8] ecn_counts=None
[2025-04-30T12:43:01.624032242Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 8
[2025-04-30T12:43:01.624055175Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 62 bytes
[2025-04-30T12:43:01.624059052Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.624062368Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.624064552Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.628458221Z TRACE quiche_server] got 96 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.628467027Z TRACE quiche_server] got packet Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75
[2025-04-30T12:43:01.628473720Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75 len=59 pn=11 
[2025-04-30T12:43:01.628480182Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm CRYPTO off=0 len=36
[2025-04-30T12:43:01.628493227Z TRACE quiche::tls] dfc364605f4ee21f080412554b31e43735dd890a set read secret lvl=OneRTT
[2025-04-30T12:43:01.628529444Z TRACE quiche::tls] dfc364605f4ee21f080412554b31e43735dd890a write message lvl=OneRTT len=396
[2025-04-30T12:43:01.628538060Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a dropped epoch 1 state
[2025-04-30T12:43:01.628542298Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a 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: 120000, stateless_reset_token: None, max_udp_payload_size: 1200, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 16777216, initial_max_stream_data_bidi_remote: 16777216, initial_max_stream_data_uni: 16777216, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(525587b2f6fdef75), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T12:43:01.628563578Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 96 bytes
[2025-04-30T12:43:01.628572294Z TRACE quiche_server] got 63 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.628576311Z TRACE quiche_server] got packet Handshake version=1 dcid=dfc364605f4ee21f080412554b31e43735dd890a scid=525587b2f6fdef75
[2025-04-30T12:43:01.628582202Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a dropped invalid packet
[2025-04-30T12:43:01.628585529Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 63 bytes
[2025-04-30T12:43:01.628590869Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.628594405Z TRACE quiche_server] got packet Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false
[2025-04-30T12:43:01.628599294Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false len=41 pn=0 
[2025-04-30T12:43:01.628605055Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T12:43:01.628613471Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 62 bytes
[2025-04-30T12:43:01.628616997Z DEBUG quiche_apps::common] dfc364605f4ee21f080412554b31e43735dd890a response stream 0 is writable with capacity Ok(12000)
[2025-04-30T12:43:01.628624311Z TRACE quiche_apps::common] dfc364605f4ee21f080412554b31e43735dd890a received 17 bytes
[2025-04-30T12:43:01.628627647Z TRACE quiche_apps::common] dfc364605f4ee21f080412554b31e43735dd890a stream 0 has 17 bytes (fin? true)
[2025-04-30T12:43:01.628632787Z INFO  quiche_apps::common] dfc364605f4ee21f080412554b31e43735dd890a got GET request for "/www/ibaooerhxc" on stream 0
[2025-04-30T12:43:01.628663785Z INFO  quiche_apps::common] dfc364605f4ee21f080412554b31e43735dd890a sending response of size 5120 on stream 0
[2025-04-30T12:43:01.628672882Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.628680977Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12000 out_len=1200 left=1169
[2025-04-30T12:43:01.628690565Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Short dcid=525587b2f6fdef75 key_phase=false len=1174 pn=10 
[2025-04-30T12:43:01.628694482Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm ACK delay=10 blocks=[0..0] ecn_counts=None
[2025-04-30T12:43:01.628698780Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[6c, 36, c4, e2, cf, 9a, 53, 85, d4, 1c, aa, c9, 36, ac, d1, 31, 0e, 5d, eb, da] reset_token=[57, 73, 21, 85, 51, 82, 42, 1d, 4e, 6f, fe, 51, bf, a1, 9d, d1]
[2025-04-30T12:43:01.628707356Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[49, 19, 37, cc, f1, c9, 12, 11, e0, c3, c2, d7, 0c, 85, 9a, e9, 66, 89, e7, 10] reset_token=[a8, 3a, 32, 50, ad, 8f, 75, 74, e9, 09, e8, cd, 8e, 1b, ff, 04]
[2025-04-30T12:43:01.628714249Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[94, 2a, 3f, 36, c9, 69, 53, cf, db, 71, 72, b7, 0b, d5, 88, f7, 8f, 87, de, 09] reset_token=[81, c4, c9, b1, 56, 13, 37, 7b, c0, 4e, a0, e9, 54, ff, 13, 0f]
[2025-04-30T12:43:01.628720971Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[8c, bf, ff, a3, 66, a8, 17, 99, 06, ff, 8c, bf, 17, b6, c5, e9, 98, 24, 2b, 70] reset_token=[c3, 33, 13, 39, 65, 74, 5e, 8e, f7, 56, f6, 05, 62, d4, b4, 09]
[2025-04-30T12:43:01.628727584Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[d0, fa, 4a, 8e, 34, fc, 63, ed, 9e, 03, 9e, 30, 74, 4b, 9c, 78, ac, d6, 62, 18] reset_token=[8e, 6e, 8a, 96, 82, a5, 8e, 57, d2, 59, 84, 8e, c7, 74, ae, 71]
[2025-04-30T12:43:01.628734076Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[6d, 8e, 0f, 7a, a1, 16, 1b, 44, a3, 73, 6e, 0e, 9f, 07, 28, 4e, 3f, 0d, de, 8b] reset_token=[4a, 62, 44, 86, e6, 00, 97, fa, 0a, e3, 39, 81, 73, 50, f0, fa]
[2025-04-30T12:43:01.628740918Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[08, bf, 0e, f6, 41, 98, 39, 23, 2e, 22, f6, 74, f2, 32, 1c, 54, 95, 66, 0a, c0] reset_token=[7c, f1, bd, d8, 44, ec, 39, 9f, 4f, 24, 0a, b6, ca, 24, 8a, ed]
[2025-04-30T12:43:01.628747701Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm HANDSHAKE_DONE
[2025-04-30T12:43:01.628750697Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm CRYPTO off=0 len=396
[2025-04-30T12:43:01.628753863Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm STREAM id=0 off=0 len=483 fin=false
[2025-04-30T12:43:01.628770053Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=83.006564ms latest_rtt=35.14381ms srtt=32.607198ms min_rtt=31.74609ms rttvar=6.373498ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1200 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { delivery_rate: 23088, is_app_limited: true, interval: 407.696204ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 569, tv_nsec: 11384529 }), send_elapsed: 0ns, ack_elapsed: 407.696204ms, rtt: 407.696204ms } } pacer=Pacer { enabled: false, capacity: 13200, used: 0, rate: 460021, last_update: Instant { tv_sec: 569, tv_nsec: 427490967 }, next_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, max_datagram_size: 1200, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.628795270Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10800 out_len=1200 left=1174
[2025-04-30T12:43:01.628799518Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Short dcid=525587b2f6fdef75 key_phase=false len=1174 pn=11 
[2025-04-30T12:43:01.628802664Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm STREAM id=0 off=483 len=1168 fin=false
[2025-04-30T12:43:01.628808605Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=83.085501ms latest_rtt=35.14381ms srtt=32.607198ms min_rtt=31.74609ms rttvar=6.373498ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=2400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 8, rate_sample: RateSample { delivery_rate: 23088, is_app_limited: true, interval: 407.696204ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 569, tv_nsec: 11384529 }), send_elapsed: 0ns, ack_elapsed: 407.696204ms, rtt: 407.696204ms } } pacer=Pacer { enabled: false, capacity: 13200, used: 0, rate: 460021, last_update: Instant { tv_sec: 569, tv_nsec: 427490967 }, next_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, max_datagram_size: 1200, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.628821248Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9600 out_len=1200 left=1174
[2025-04-30T12:43:01.628824975Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Short dcid=525587b2f6fdef75 key_phase=false len=1174 pn=12 
[2025-04-30T12:43:01.628827981Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm STREAM id=0 off=1651 len=1168 fin=false
[2025-04-30T12:43:01.628833171Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=83.087494ms latest_rtt=35.14381ms srtt=32.607198ms min_rtt=31.74609ms rttvar=6.373498ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=3600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 8, rate_sample: RateSample { delivery_rate: 23088, is_app_limited: true, interval: 407.696204ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 569, tv_nsec: 11384529 }), send_elapsed: 0ns, ack_elapsed: 407.696204ms, rtt: 407.696204ms } } pacer=Pacer { enabled: false, capacity: 13200, used: 0, rate: 460021, last_update: Instant { tv_sec: 569, tv_nsec: 427490967 }, next_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, max_datagram_size: 1200, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.628844632Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8400 out_len=1200 left=1174
[2025-04-30T12:43:01.628851595Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Short dcid=525587b2f6fdef75 key_phase=false len=1174 pn=13 
[2025-04-30T12:43:01.628854631Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm STREAM id=0 off=2819 len=1168 fin=false
[2025-04-30T12:43:01.628859851Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=83.084408ms latest_rtt=35.14381ms srtt=32.607198ms min_rtt=31.74609ms rttvar=6.373498ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=4800 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 8, rate_sample: RateSample { delivery_rate: 23088, is_app_limited: true, interval: 407.696204ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 569, tv_nsec: 11384529 }), send_elapsed: 0ns, ack_elapsed: 407.696204ms, rtt: 407.696204ms } } pacer=Pacer { enabled: false, capacity: 13200, used: 0, rate: 460021, last_update: Instant { tv_sec: 569, tv_nsec: 427490967 }, next_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, max_datagram_size: 1200, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.628871252Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7200 out_len=1200 left=1174
[2025-04-30T12:43:01.628874848Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Short dcid=525587b2f6fdef75 key_phase=false len=1139 pn=14 
[2025-04-30T12:43:01.628877784Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm STREAM id=0 off=3987 len=1133 fin=true
[2025-04-30T12:43:01.628883384Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=83.087505ms latest_rtt=35.14381ms srtt=32.607198ms min_rtt=31.74609ms rttvar=6.373498ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=5965 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 8, rate_sample: RateSample { delivery_rate: 23088, is_app_limited: true, interval: 407.696204ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 569, tv_nsec: 11384529 }), send_elapsed: 0ns, ack_elapsed: 407.696204ms, rtt: 407.696204ms } } pacer=Pacer { enabled: false, capacity: 13200, used: 0, rate: 460021, last_update: Instant { tv_sec: 569, tv_nsec: 427490967 }, next_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, max_datagram_size: 1200, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.628977891Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 5965 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 427490967 } })
[2025-04-30T12:43:01.628984052Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.628989452Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.628992879Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.628995594Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.660745778Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.660764032Z TRACE quiche_server] got packet Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false
[2025-04-30T12:43:01.660771777Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false len=48 pn=1 
[2025-04-30T12:43:01.660780633Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[35, cd, 6c, 73, b9, 6a, b2, b4] reset_token=[ea, 18, 1f, 41, 53, 8d, 04, f3, 32, df, ad, 01, 9d, 58, d7, b4]
[2025-04-30T12:43:01.660793146Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 69 bytes
[2025-04-30T12:43:01.660799759Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.660803376Z TRACE quiche_server] got packet Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false
[2025-04-30T12:43:01.660808105Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false len=48 pn=2 
[2025-04-30T12:43:01.660814236Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[79, bf, 45, b4, 3f, 7c, cc, e1] reset_token=[0b, 2b, 0e, 37, 25, ae, 7d, ba, c4, de, 75, 39, f8, fa, c2, bb]
[2025-04-30T12:43:01.660821419Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 69 bytes
[2025-04-30T12:43:01.660825517Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.660831328Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a pmtud probe status false hs_con=true hs_sent=true cwnd_avail=6035 out_len=1200 left=1169
[2025-04-30T12:43:01.660835526Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx pkt Short dcid=525587b2f6fdef75 key_phase=false len=5 pn=15 
[2025-04-30T12:43:01.660838702Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a tx frm ACK delay=2 blocks=[0..2] ecn_counts=None
[2025-04-30T12:43:01.660846757Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a timer=51.124002ms latest_rtt=35.14381ms srtt=32.607198ms min_rtt=31.74609ms rttvar=6.373498ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=5965 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9413, delivered_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, first_sent_time: Instant { tv_sec: 569, tv_nsec: 427490967 }, end_of_app_limited: 13, last_sent_packet: 15, largest_acked: 8, rate_sample: RateSample { delivery_rate: 23088, is_app_limited: true, interval: 407.696204ms, delivered: 9413, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 569, tv_nsec: 11384529 }), send_elapsed: 0ns, ack_elapsed: 407.696204ms, rtt: 407.696204ms } } pacer=Pacer { enabled: false, capacity: 13200, used: 0, rate: 460021, last_update: Instant { tv_sec: 569, tv_nsec: 459643181 }, next_time: Instant { tv_sec: 569, tv_nsec: 459643181 }, max_datagram_size: 1200, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T12:43:01.660896359Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a written 31 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41533, at: Instant { tv_sec: 569, tv_nsec: 459643181 } })
[2025-04-30T12:43:01.660902360Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.660907600Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.660910986Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.660913722Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.661364323Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.661377387Z TRACE quiche_server] got packet Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false
[2025-04-30T12:43:01.661383448Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false len=25 pn=3 
[2025-04-30T12:43:01.661389620Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm ACK delay=14 blocks=[10..11] ecn_counts=None
[2025-04-30T12:43:01.661393928Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 10
[2025-04-30T12:43:01.661398226Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 11
[2025-04-30T12:43:01.661411871Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 46 bytes
[2025-04-30T12:43:01.661416700Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.661420006Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.661422711Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.663318559Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.663325632Z TRACE quiche_server] got packet Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false
[2025-04-30T12:43:01.663330732Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false len=25 pn=4 
[2025-04-30T12:43:01.663335902Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm ACK delay=13 blocks=[10..13] ecn_counts=None
[2025-04-30T12:43:01.663339658Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 12
[2025-04-30T12:43:01.663342514Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 13
[2025-04-30T12:43:01.663351851Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 46 bytes
[2025-04-30T12:43:01.663356239Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.663359335Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.663362000Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:01.689950656Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:01.689964592Z TRACE quiche_server] got packet Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false
[2025-04-30T12:43:01.689972567Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false len=26 pn=11 
[2025-04-30T12:43:01.689980081Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm ACK delay=3213 blocks=[10..14] ecn_counts=None
[2025-04-30T12:43:01.689984830Z TRACE quiche::recovery::congestion::recovery] dfc364605f4ee21f080412554b31e43735dd890a packet newly acked 14
[2025-04-30T12:43:01.689999708Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 47 bytes
[2025-04-30T12:43:01.690005248Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:01.690009055Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:01.690012071Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:02.011329364Z TRACE quiche_server] got 45 bytes from [::ffff:193.167.0.100]:41533 to [::]:443
[2025-04-30T12:43:02.011341056Z TRACE quiche_server] got packet Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false
[2025-04-30T12:43:02.011348771Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx pkt Short dcid=dfc364605f4ee21f080412554b31e43735dd890a key_phase=false len=24 pn=12 
[2025-04-30T12:43:02.011356024Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T12:43:02.011379718Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a processed 45 bytes
[2025-04-30T12:43:02.011385259Z TRACE quiche_server] recv() would block
[2025-04-30T12:43:02.011391471Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:02.011394576Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:02.158587306Z TRACE quiche_server] timed out
[2025-04-30T12:43:02.158599949Z TRACE quiche] dfc364605f4ee21f080412554b31e43735dd890a draining timeout expired
[2025-04-30T12:43:02.158625146Z TRACE quiche_server] dfc364605f4ee21f080412554b31e43735dd890a done writing
[2025-04-30T12:43:02.158628843Z TRACE quiche_server] Collecting garbage
[2025-04-30T12:43:02.158632159Z INFO  quiche_server] dfc364605f4ee21f080412554b31e43735dd890a connection collected recv=15 sent=16 lost=0 retrans=0 sent_bytes=16660 recv_bytes=2048 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:41533 validation_state=Validated active=true recv=15 sent=16 lost=0 retrans=0 rtt=33.279537ms min_rtt=Some(31.74609ms) rttvar=3.91686ms cwnd=12000 sent_bytes=16660 recv_bytes=2048 lost_bytes=0 stream_retrans_bytes=0 pmtu=1200 delivery_rate=182378]