[2025-04-30T09:12:23.283561030Z INFO  quiche_server] listening on [::]:443
[2025-04-30T09:12:23.283609931Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T09:12:23.283621272Z TRACE quiche_server] GSO detected: false
[2025-04-30T09:12:24.085754261Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:48431 to [::]:443
[2025-04-30T09:12:24.085791100Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:12:24.085799926Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:12:24.085895303Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:48431 to [::]:443
[2025-04-30T09:12:24.085902296Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:12:24.085906433Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:12:24.085931820Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:48431 to [::]:443
[2025-04-30T09:12:24.085938443Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:12:24.085944173Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:12:24.086010667Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:24.319240521Z TRACE quiche_server] got 1220 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:24.319264175Z TRACE quiche_server] got packet Initial version=1 dcid=4b2119cea78105cc scid= token=
[2025-04-30T09:12:24.319271117Z DEBUG quiche_server] New connection: dcid=4b2119cea78105cc scid=532d049688184e4365af8f82b3c0e937f226ef6e
[2025-04-30T09:12:24.319484042Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Initial version=1 dcid=4b2119cea78105cc scid= token= len=1202 pn=0 
[2025-04-30T09:12:24.319499701Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm CRYPTO off=0 len=290
[2025-04-30T09:12:24.319531660Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T09:12:24.319536539Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T09:12:24.319688571Z TRACE quiche::tls] 532d049688184e4365af8f82b3c0e937f226ef6e write message lvl=Initial len=90
[2025-04-30T09:12:24.319728194Z TRACE quiche::tls] 532d049688184e4365af8f82b3c0e937f226ef6e set write secret lvl=Handshake
[2025-04-30T09:12:24.319845462Z TRACE quiche::tls] 532d049688184e4365af8f82b3c0e937f226ef6e write message lvl=Handshake len=9961
[2025-04-30T09:12:24.319850912Z TRACE quiche::tls] 532d049688184e4365af8f82b3c0e937f226ef6e set write secret lvl=OneRTT
[2025-04-30T09:12:24.319861431Z TRACE quiche::tls] 532d049688184e4365af8f82b3c0e937f226ef6e set read secret lvl=Handshake
[2025-04-30T09:12:24.319886708Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PADDING len=888
[2025-04-30T09:12:24.319909701Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 1220 bytes
[2025-04-30T09:12:24.319924779Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:24.319938814Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Initial version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=99 pn=0 
[2025-04-30T09:12:24.319943213Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm ACK delay=59 blocks=[0..0] ecn_counts=None
[2025-04-30T09:12:24.319948923Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=0 len=90
[2025-04-30T09:12:24.319960935Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=998.965997ms 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: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 450, tv_nsec: 575352902 }, next_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:24.319997523Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=1008 pn=1 
[2025-04-30T09:12:24.320001541Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=0 len=1004
[2025-04-30T09:12:24.320008944Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=998.918399ms 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: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 450, tv_nsec: 575352902 }, next_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:24.320065569Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 450, tv_nsec: 575352902 } })
[2025-04-30T09:12:24.320073364Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:24.320080457Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:24.320095275Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=1154 pn=2 
[2025-04-30T09:12:24.320101386Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=1004 len=1149
[2025-04-30T09:12:24.320114891Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=998.811792ms 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: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 450, tv_nsec: 575509762 }, next_time: Instant { tv_sec: 450, tv_nsec: 575509762 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:24.320177116Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 450, tv_nsec: 575509762 } })
[2025-04-30T09:12:24.320195710Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:24.320204497Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:24.320221178Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=1154 pn=3 
[2025-04-30T09:12:24.320227509Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=2153 len=1149
[2025-04-30T09:12:24.320242648Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=998.683935ms 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: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 450, tv_nsec: 575634453 }, next_time: Instant { tv_sec: 450, tv_nsec: 575634453 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:24.320293362Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 450, tv_nsec: 575634453 } })
[2025-04-30T09:12:24.320300334Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:24.320308499Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:24.320324830Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=14 pn=4 
[2025-04-30T09:12:24.320331913Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=3302 len=9
[2025-04-30T09:12:24.320345087Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=998.581685ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3660 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 450, tv_nsec: 575738245 }, next_time: Instant { tv_sec: 450, tv_nsec: 575738245 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:24.320389800Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 60 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 450, tv_nsec: 575738245 } })
[2025-04-30T09:12:24.320394810Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:24.320400770Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:24.320406000Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:24.320416379Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:25.320474813Z TRACE quiche_server] timed out
[2025-04-30T09:12:25.320502224Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e loss detection timeout expired
[2025-04-30T09:12:25.320510509Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=997.415963ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3660 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 450, tv_nsec: 575738245 }, next_time: Instant { tv_sec: 450, tv_nsec: 575738245 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:25.320544622Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:25.320548469Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:26.319604334Z TRACE quiche_server] timed out
[2025-04-30T09:12:26.319629440Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e loss detection timeout expired
[2025-04-30T09:12:26.319636403Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=1.996290019s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3660 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 450, tv_nsec: 575738245 }, next_time: Instant { tv_sec: 450, tv_nsec: 575738245 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:26.319671228Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:26.319675185Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.318728051Z TRACE quiche_server] timed out
[2025-04-30T09:12:28.318761553Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e loss detection timeout expired
[2025-04-30T09:12:28.318774176Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=3.993151895s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3660 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 450, tv_nsec: 575738245 }, next_time: Instant { tv_sec: 450, tv_nsec: 575738245 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.318825301Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.318829169Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.582741872Z TRACE quiche_server] got 1220 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.582777969Z TRACE quiche_server] got packet Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid=
[2025-04-30T09:12:28.582794059Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid= len=1191 pn=8 
[2025-04-30T09:12:28.582807945Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PING mtu_probe=None
[2025-04-30T09:12:28.582821189Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PADDING len=1170
[2025-04-30T09:12:28.582850143Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e dropped epoch 0 state
[2025-04-30T09:12:28.582854431Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 1220 bytes
[2025-04-30T09:12:28.582861023Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.582882082Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=1154 pn=5 
[2025-04-30T09:12:28.582886160Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm ACK delay=10 blocks=[8..8] ecn_counts=None
[2025-04-30T09:12:28.582892752Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=3311 len=1144
[2025-04-30T09:12:28.582906337Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=7.99195703s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4714 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 454, tv_nsec: 838289386 }, next_time: Instant { tv_sec: 454, tv_nsec: 838289386 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.582982549Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 838289386 } })
[2025-04-30T09:12:28.582990273Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.582996234Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.583005411Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=1154 pn=6 
[2025-04-30T09:12:28.583009068Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=4455 len=1149
[2025-04-30T09:12:28.583033744Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=7.991964754s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5914 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 454, tv_nsec: 838424036 }, next_time: Instant { tv_sec: 454, tv_nsec: 838424036 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.583075351Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 838424036 } })
[2025-04-30T09:12:28.583081102Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.583085570Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.583094447Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=1154 pn=7 
[2025-04-30T09:12:28.583097893Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=5604 len=1149
[2025-04-30T09:12:28.583105307Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=7.991982507s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7114 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 454, tv_nsec: 838513312 }, next_time: Instant { tv_sec: 454, tv_nsec: 838513312 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.583141153Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 838513312 } })
[2025-04-30T09:12:28.583146533Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.583150881Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.583159217Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=1154 pn=8 
[2025-04-30T09:12:28.583162573Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=6753 len=1149
[2025-04-30T09:12:28.583170127Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=7.991982898s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8314 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 454, tv_nsec: 838578503 }, next_time: Instant { tv_sec: 454, tv_nsec: 838578503 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.583211433Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 838578503 } })
[2025-04-30T09:12:28.583217224Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.583221572Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.583229848Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=1154 pn=9 
[2025-04-30T09:12:28.583233184Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=7902 len=1149
[2025-04-30T09:12:28.583241179Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=7.991982427s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9514 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 454, tv_nsec: 838649164 }, next_time: Instant { tv_sec: 454, tv_nsec: 838649164 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.583276404Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 838649164 } })
[2025-04-30T09:12:28.583280832Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.583284219Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.583290821Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=915 pn=10 
[2025-04-30T09:12:28.583293596Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=9051 len=910
[2025-04-30T09:12:28.583299617Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=7.991986345s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10475 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 454, tv_nsec: 838711300 }, next_time: Instant { tv_sec: 454, tv_nsec: 838711300 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.583332789Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 961 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 838711300 } })
[2025-04-30T09:12:28.583337407Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.583340804Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.583343739Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.583346033Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.615783839Z TRACE quiche_server] got 1220 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.615852706Z TRACE quiche_server] got packet Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid=
[2025-04-30T09:12:28.615879977Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid= len=1191 pn=9 
[2025-04-30T09:12:28.615906045Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm ACK delay=0 blocks=[1..5] ecn_counts=None
[2025-04-30T09:12:28.615919150Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 1
[2025-04-30T09:12:28.615924600Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 2
[2025-04-30T09:12:28.615928207Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 3
[2025-04-30T09:12:28.615930941Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 4
[2025-04-30T09:12:28.615936031Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 5
[2025-04-30T09:12:28.615946370Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PING mtu_probe=None
[2025-04-30T09:12:28.615955367Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PADDING len=1165
[2025-04-30T09:12:28.616001082Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 1220 bytes
[2025-04-30T09:12:28.616010620Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.616041246Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Handshake version=1 dcid= scid=532d049688184e4365af8f82b3c0e937f226ef6e len=5 pn=11 
[2025-04-30T09:12:28.616045755Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm ACK delay=20 blocks=[9..9] ecn_counts=None
[2025-04-30T09:12:28.616060612Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=66.246754ms latest_rtt=33.007643ms srtt=33.007643ms min_rtt=33.007643ms rttvar=16.503821ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5761 app_limited=true congestion_recovery_start_time=None Rate { delivered: 4714, delivered_time: Instant { tv_sec: 454, tv_nsec: 871297029 }, first_sent_time: Instant { tv_sec: 450, tv_nsec: 575352902 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 5, rate_sample: RateSample { delivery_rate: 1097, is_app_limited: true, interval: 4.295944127s, delivered: 4714, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 450, tv_nsec: 575352902 }), send_elapsed: 0ns, ack_elapsed: 4.295944127s, rtt: 4.295944127s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 511245, last_update: Instant { tv_sec: 454, tv_nsec: 871438893 }, next_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.616245496Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 51 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 871438893 } })
[2025-04-30T09:12:28.616262577Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.616273077Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.616278987Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.616281923Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.616408969Z TRACE quiche_server] got 54 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.616414369Z TRACE quiche_server] got packet Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid=
[2025-04-30T09:12:28.616422394Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid= len=25 pn=10 
[2025-04-30T09:12:28.616429437Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm ACK delay=0 blocks=[1..7] ecn_counts=None
[2025-04-30T09:12:28.616433955Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 6
[2025-04-30T09:12:28.616437582Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 7
[2025-04-30T09:12:28.616450987Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 54 bytes
[2025-04-30T09:12:28.616455375Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.616459763Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.616462348Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.618633958Z TRACE quiche_server] got 54 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.618700943Z TRACE quiche_server] got packet Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid=
[2025-04-30T09:12:28.618728604Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid= len=25 pn=11 
[2025-04-30T09:12:28.618753140Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm ACK delay=0 blocks=[1..9] ecn_counts=None
[2025-04-30T09:12:28.618764661Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 8
[2025-04-30T09:12:28.618769661Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 9
[2025-04-30T09:12:28.618882109Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 54 bytes
[2025-04-30T09:12:28.618893070Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.618912716Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.618916493Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.620525620Z TRACE quiche_server] got 109 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.620621267Z TRACE quiche_server] got packet Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid=
[2025-04-30T09:12:28.620649299Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Handshake version=1 dcid=532d049688184e4365af8f82b3c0e937f226ef6e scid= len=80 pn=12 
[2025-04-30T09:12:28.620668815Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm ACK delay=4 blocks=[1..10] ecn_counts=None
[2025-04-30T09:12:28.620678573Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 10
[2025-04-30T09:12:28.620709160Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm CRYPTO off=0 len=52
[2025-04-30T09:12:28.620748493Z TRACE quiche::tls] 532d049688184e4365af8f82b3c0e937f226ef6e set read secret lvl=OneRTT
[2025-04-30T09:12:28.620808194Z TRACE quiche::tls] 532d049688184e4365af8f82b3c0e937f226ef6e write message lvl=OneRTT len=428
[2025-04-30T09:12:28.620823493Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e dropped epoch 1 state
[2025-04-30T09:12:28.620829554Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e connection established: proto=Ok("hq-interop") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 50000, stateless_reset_token: None, max_udp_payload_size: 1472, initial_max_data: 16777216, initial_max_stream_data_bidi_local: 65536, initial_max_stream_data_bidi_remote: 65536, initial_max_stream_data_uni: 65536, initial_max_streams_bidi: 0, initial_max_streams_uni: 3, ack_delay_exponent: 8, max_ack_delay: 26, disable_active_migration: false, active_conn_id_limit: 4, initial_source_connection_id: Some(), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T09:12:28.620879647Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 109 bytes
[2025-04-30T09:12:28.620889906Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.620898392Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1332
[2025-04-30T09:12:28.620913359Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Short dcid= key_phase=false len=553 pn=12 
[2025-04-30T09:12:28.620916325Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[2d, 02, 50, 91, a2, d7, a8, d1, a4, a4, 9e, 84, 08, 5f, 39, c7, aa, 58, ef, 1f] reset_token=[41, 32, 05, dd, a6, b3, 0c, ae, 63, 58, 9a, 5e, f4, 47, 5d, b9]
[2025-04-30T09:12:28.620926624Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[67, e3, 96, 8a, b6, 04, 99, 5b, a3, 59, 22, 58, 96, 37, 01, a8, 5b, 34, 75, d6] reset_token=[0c, f9, 56, 00, b8, 3d, 15, 2f, 52, d1, 7b, 6d, 5a, c2, e6, 41]
[2025-04-30T09:12:28.620933647Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[26, 97, a8, e6, 72, d2, 10, b4, 31, 1b, 22, f5, 65, 83, 15, 64, a0, 17, 8b, 03] reset_token=[9f, 2d, 29, 9e, 58, 9e, a0, ca, 7a, c7, 1f, 57, 43, 08, 5f, 1e]
[2025-04-30T09:12:28.620940209Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm HANDSHAKE_DONE
[2025-04-30T09:12:28.620942674Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm CRYPTO off=0 len=428
[2025-04-30T09:12:28.620959475Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=92.505124ms latest_rtt=37.355242ms srtt=33.726433ms min_rtt=33.007643ms rttvar=8.211534ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=571 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10475, delivered_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, first_sent_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 10, rate_sample: RateSample { delivery_rate: 2438, is_app_limited: true, interval: 4.295944127s, delivered: 10475, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 450, tv_nsec: 575352902 }), send_elapsed: 0ns, ack_elapsed: 4.295944127s, rtt: 4.295944127s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 500349, last_update: Instant { tv_sec: 454, tv_nsec: 871438893 }, next_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.621090789Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 571 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 871438893 } })
[2025-04-30T09:12:28.621099274Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.621105596Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.621109123Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.621111587Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.621446429Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.621457750Z TRACE quiche_server] got packet Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false
[2025-04-30T09:12:28.621467227Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false len=1231 pn=13 
[2025-04-30T09:12:28.621476655Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PING mtu_probe=None
[2025-04-30T09:12:28.621481303Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T09:12:28.621491172Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PADDING len=1189
[2025-04-30T09:12:28.621503665Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 1252 bytes
[2025-04-30T09:12:28.621507743Z DEBUG quiche_apps::common] 532d049688184e4365af8f82b3c0e937f226ef6e response stream 0 is writable with capacity Ok(12929)
[2025-04-30T09:12:28.621517310Z TRACE quiche_apps::common] 532d049688184e4365af8f82b3c0e937f226ef6e received 17 bytes
[2025-04-30T09:12:28.621520446Z TRACE quiche_apps::common] 532d049688184e4365af8f82b3c0e937f226ef6e stream 0 has 17 bytes (fin? true)
[2025-04-30T09:12:28.621527108Z INFO  quiche_apps::common] 532d049688184e4365af8f82b3c0e937f226ef6e got GET request for "/www/afgvrsihxc" on stream 0
[2025-04-30T09:12:28.621616384Z INFO  quiche_apps::common] 532d049688184e4365af8f82b3c0e937f226ef6e sending response of size 5120 on stream 0
[2025-04-30T09:12:28.621638215Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.621645518Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12929 out_len=1350 left=1327
[2025-04-30T09:12:28.621651389Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Short dcid= key_phase=false len=1332 pn=13 
[2025-04-30T09:12:28.621654675Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm ACK delay=22 blocks=[13..13] ecn_counts=None
[2025-04-30T09:12:28.621658903Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm STREAM id=0 off=0 len=1322 fin=false
[2025-04-30T09:12:28.621668611Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=92.543936ms latest_rtt=37.355242ms srtt=33.726433ms min_rtt=33.007643ms rttvar=8.211534ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1921 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10475, delivered_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, first_sent_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 10, rate_sample: RateSample { delivery_rate: 2438, is_app_limited: true, interval: 4.295944127s, delivered: 10475, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 450, tv_nsec: 575352902 }), send_elapsed: 0ns, ack_elapsed: 4.295944127s, rtt: 4.295944127s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 500349, last_update: Instant { tv_sec: 454, tv_nsec: 871438893 }, next_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.621695451Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11579 out_len=1350 left=1332
[2025-04-30T09:12:28.621699228Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Short dcid= key_phase=false len=1332 pn=14 
[2025-04-30T09:12:28.621701843Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm STREAM id=0 off=1322 len=1326 fin=false
[2025-04-30T09:12:28.621707824Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=92.558533ms latest_rtt=37.355242ms srtt=33.726433ms min_rtt=33.007643ms rttvar=8.211534ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3271 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10475, delivered_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, first_sent_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 10, rate_sample: RateSample { delivery_rate: 2438, is_app_limited: true, interval: 4.295944127s, delivered: 10475, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 450, tv_nsec: 575352902 }), send_elapsed: 0ns, ack_elapsed: 4.295944127s, rtt: 4.295944127s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 500349, last_update: Instant { tv_sec: 454, tv_nsec: 871438893 }, next_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.621719045Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10229 out_len=1350 left=1332
[2025-04-30T09:12:28.621722221Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Short dcid= key_phase=false len=1332 pn=15 
[2025-04-30T09:12:28.621724575Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm STREAM id=0 off=2648 len=1326 fin=false
[2025-04-30T09:12:28.621729043Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=92.561679ms latest_rtt=37.355242ms srtt=33.726433ms min_rtt=33.007643ms rttvar=8.211534ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4621 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10475, delivered_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, first_sent_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 10, rate_sample: RateSample { delivery_rate: 2438, is_app_limited: true, interval: 4.295944127s, delivered: 10475, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 450, tv_nsec: 575352902 }), send_elapsed: 0ns, ack_elapsed: 4.295944127s, rtt: 4.295944127s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 500349, last_update: Instant { tv_sec: 454, tv_nsec: 871438893 }, next_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.621739202Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8879 out_len=1350 left=1332
[2025-04-30T09:12:28.621742338Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Short dcid= key_phase=false len=1152 pn=16 
[2025-04-30T09:12:28.621748139Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm STREAM id=0 off=3974 len=1146 fin=true
[2025-04-30T09:12:28.621754040Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=92.55693ms latest_rtt=37.355242ms srtt=33.726433ms min_rtt=33.007643ms rttvar=8.211534ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5791 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10475, delivered_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, first_sent_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 10, rate_sample: RateSample { delivery_rate: 2438, is_app_limited: true, interval: 4.295944127s, delivered: 10475, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 450, tv_nsec: 575352902 }), send_elapsed: 0ns, ack_elapsed: 4.295944127s, rtt: 4.295944127s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 500349, last_update: Instant { tv_sec: 454, tv_nsec: 871438893 }, next_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.621853695Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 5220 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 871438893 } })
[2025-04-30T09:12:28.621863523Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.621868512Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.621871668Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.621873912Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.653456925Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.653488664Z TRACE quiche_server] got packet Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false
[2025-04-30T09:12:28.653504052Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false len=25 pn=14 
[2025-04-30T09:12:28.653518489Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm ACK delay=0 blocks=[12..13] ecn_counts=None
[2025-04-30T09:12:28.653526534Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 12
[2025-04-30T09:12:28.653530882Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 13
[2025-04-30T09:12:28.653559846Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 46 bytes
[2025-04-30T09:12:28.653568071Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.653573231Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.653599409Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.654465577Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.654470897Z TRACE quiche_server] got packet Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false
[2025-04-30T09:12:28.654476477Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false len=1311 pn=15 
[2025-04-30T09:12:28.654483089Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PING mtu_probe=None
[2025-04-30T09:12:28.654490814Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm PADDING len=1290
[2025-04-30T09:12:28.654497556Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 1332 bytes
[2025-04-30T09:12:28.654501433Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.654508847Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9630 out_len=1350 left=1327
[2025-04-30T09:12:28.654526881Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Short dcid= key_phase=false len=5 pn=17 
[2025-04-30T09:12:28.654529816Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm ACK delay=3 blocks=[14..15] ecn_counts=None
[2025-04-30T09:12:28.654538783Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=55.790479ms latest_rtt=37.48656ms srtt=34.196448ms min_rtt=33.007643ms rttvar=7.098681ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3870 app_limited=true congestion_recovery_start_time=None Rate { delivered: 12396, delivered_time: Instant { tv_sec: 454, tv_nsec: 908925453 }, first_sent_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 13, rate_sample: RateSample { delivery_rate: 51245, is_app_limited: true, interval: 37.48656ms, delivered: 1921, prior_delivered: 10475, prior_time: Some(Instant { tv_sec: 454, tv_nsec: 871438893 }), send_elapsed: 0ns, ack_elapsed: 37.48656ms, rtt: 37.48656ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493472, last_update: Instant { tv_sec: 454, tv_nsec: 909928635 }, next_time: Instant { tv_sec: 454, tv_nsec: 909928635 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.654629832Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 909928635 } })
[2025-04-30T09:12:28.654640391Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.654644890Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.654647965Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.654650350Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.655525304Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.655530213Z TRACE quiche_server] got packet Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false
[2025-04-30T09:12:28.655535853Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false len=36 pn=16 
[2025-04-30T09:12:28.655542115Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm ACK delay=0 blocks=[12..15] ecn_counts=None
[2025-04-30T09:12:28.655545591Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 14
[2025-04-30T09:12:28.655548196Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 15
[2025-04-30T09:12:28.655551723Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm MAX_DATA max=16781190
[2025-04-30T09:12:28.655554418Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm MAX_STREAM_DATA stream=0 max=69510
[2025-04-30T09:12:28.655564777Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 57 bytes
[2025-04-30T09:12:28.655568714Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.655573113Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12330 out_len=1350 left=1327
[2025-04-30T09:12:28.655595424Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx pkt Short dcid= key_phase=false len=5 pn=18 
[2025-04-30T09:12:28.655602487Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e tx frm ACK delay=4 blocks=[14..16] ecn_counts=None
[2025-04-30T09:12:28.655608909Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e timer=53.612007ms latest_rtt=39.521106ms srtt=34.86203ms min_rtt=33.007643ms rttvar=6.655174ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1170 app_limited=true congestion_recovery_start_time=None Rate { delivered: 15096, delivered_time: Instant { tv_sec: 454, tv_nsec: 910959999 }, first_sent_time: Instant { tv_sec: 454, tv_nsec: 871438893 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 15, rate_sample: RateSample { delivery_rate: 123270, is_app_limited: true, interval: 37.48656ms, delivered: 4621, prior_delivered: 10475, prior_time: Some(Instant { tv_sec: 454, tv_nsec: 871438893 }), send_elapsed: 0ns, ack_elapsed: 37.48656ms, rtt: 37.48656ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 484050, last_update: Instant { tv_sec: 454, tv_nsec: 909928635 }, next_time: Instant { tv_sec: 454, tv_nsec: 909928635 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:12:28.655656908Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43865, at: Instant { tv_sec: 454, tv_nsec: 909928635 } })
[2025-04-30T09:12:28.655663130Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.655667127Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.655669992Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.655672166Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.656705765Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:43865 to [::]:443
[2025-04-30T09:12:28.656712096Z TRACE quiche_server] got packet Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false
[2025-04-30T09:12:28.656717466Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx pkt Short dcid=532d049688184e4365af8f82b3c0e937f226ef6e key_phase=false len=28 pn=17 
[2025-04-30T09:12:28.656723087Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm ACK delay=1 blocks=[12..16] ecn_counts=None
[2025-04-30T09:12:28.656726453Z TRACE quiche::recovery::congestion::recovery] 532d049688184e4365af8f82b3c0e937f226ef6e packet newly acked 16
[2025-04-30T09:12:28.656730130Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e rx frm APPLICATION_CLOSE err=0 reason=[]
[2025-04-30T09:12:28.656743254Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e processed 49 bytes
[2025-04-30T09:12:28.656747202Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:28.656749476Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.656751630Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.841006666Z TRACE quiche_server] timed out
[2025-04-30T09:12:28.841040188Z TRACE quiche] 532d049688184e4365af8f82b3c0e937f226ef6e draining timeout expired
[2025-04-30T09:12:28.841089069Z TRACE quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e done writing
[2025-04-30T09:12:28.841093136Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:28.841097935Z INFO  quiche_server] 532d049688184e4365af8f82b3c0e937f226ef6e connection collected recv=11 sent=19 lost=0 retrans=0 sent_bytes=16509 recv_bytes=6613 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:43865 validation_state=Validated active=true recv=11 sent=19 lost=0 retrans=0 rtt=35.560114ms min_rtt=Some(33.007643ms) rttvar=6.387549ms cwnd=13500 sent_bytes=16509 recv_bytes=6613 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=154482]