[2025-04-30T09:12:40.090889201Z INFO  quiche_server] listening on [::]:443
[2025-04-30T09:12:40.090910291Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T09:12:40.090916824Z TRACE quiche_server] GSO detected: false
[2025-04-30T09:12:40.928711348Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38223 to [::]:443
[2025-04-30T09:12:40.928746164Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:12:40.928754379Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:12:40.928840752Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38223 to [::]:443
[2025-04-30T09:12:40.928847875Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:12:40.928854227Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:12:40.928886208Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38223 to [::]:443
[2025-04-30T09:12:40.928893391Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:12:40.928899783Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:12:40.928926213Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.130029256Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:41.130051067Z TRACE quiche_server] got packet Initial version=1 dcid=db5eab8166063c535a4eaade69fab7ca scid=3c4caa78f50e75205f3181def2204a9aa1718061 token=
[2025-04-30T09:12:41.130060414Z DEBUG quiche_server] New connection: dcid=db5eab8166063c535a4eaade69fab7ca scid=c158c038dbb906457189d8a09cd0ef2f67adbbea
[2025-04-30T09:12:41.130244481Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Initial version=1 dcid=db5eab8166063c535a4eaade69fab7ca scid=3c4caa78f50e75205f3181def2204a9aa1718061 token= len=287 pn=0 
[2025-04-30T09:12:41.130258888Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm CRYPTO off=0 len=266
[2025-04-30T09:12:41.130287122Z TRACE quiche::tls] checking peer ALPN Ok("h3") against Ok("h3")
[2025-04-30T09:12:41.130389024Z TRACE quiche::tls] c158c038dbb906457189d8a09cd0ef2f67adbbea write message lvl=Initial len=90
[2025-04-30T09:12:41.130417678Z TRACE quiche::tls] c158c038dbb906457189d8a09cd0ef2f67adbbea set write secret lvl=Handshake
[2025-04-30T09:12:41.130524389Z TRACE quiche::tls] c158c038dbb906457189d8a09cd0ef2f67adbbea write message lvl=Handshake len=9943
[2025-04-30T09:12:41.130529619Z TRACE quiche::tls] c158c038dbb906457189d8a09cd0ef2f67adbbea set write secret lvl=OneRTT
[2025-04-30T09:12:41.130569935Z TRACE quiche::tls] c158c038dbb906457189d8a09cd0ef2f67adbbea set read secret lvl=Handshake
[2025-04-30T09:12:41.130610441Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea dropped invalid packet
[2025-04-30T09:12:41.130614529Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 1200 bytes
[2025-04-30T09:12:41.130636200Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.130650677Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Initial version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=99 pn=0 
[2025-04-30T09:12:41.130654665Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm ACK delay=53 blocks=[0..0] ecn_counts=None
[2025-04-30T09:12:41.130660055Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=0 len=90
[2025-04-30T09:12:41.130671025Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=998.967549ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=166 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 369, tv_nsec: 833106482 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 833106482 }, 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: 369, tv_nsec: 833106482 }, next_time: Instant { tv_sec: 369, tv_nsec: 833106482 }, 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:41.130705100Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=968 pn=1 
[2025-04-30T09:12:41.130708876Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=0 len=964
[2025-04-30T09:12:41.130715720Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=998.923276ms 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: 369, tv_nsec: 833106482 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 833106482 }, 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: 369, tv_nsec: 833106482 }, next_time: Instant { tv_sec: 369, tv_nsec: 833106482 }, 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:41.130768599Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 369, tv_nsec: 833106482 } })
[2025-04-30T09:12:41.130779810Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.130787976Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.130804817Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1134 pn=2 
[2025-04-30T09:12:41.130809987Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=964 len=1129
[2025-04-30T09:12:41.130819084Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=998.819881ms 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: 369, tv_nsec: 833106482 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 833106482 }, 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: 369, tv_nsec: 833259100 }, next_time: Instant { tv_sec: 369, tv_nsec: 833259100 }, 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:41.130867906Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 369, tv_nsec: 833259100 } })
[2025-04-30T09:12:41.130878005Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.130885319Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.130902231Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1134 pn=3 
[2025-04-30T09:12:41.130906950Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=2093 len=1129
[2025-04-30T09:12:41.130915376Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=998.723539ms 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: 369, tv_nsec: 833106482 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 833106482 }, 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: 369, tv_nsec: 833356774 }, next_time: Instant { tv_sec: 369, tv_nsec: 833356774 }, 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:41.130955341Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 369, tv_nsec: 833356774 } })
[2025-04-30T09:12:41.130964198Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.130970890Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.130977273Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:41.130981651Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.965910206Z TRACE quiche_server] got 75 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:41.965934181Z TRACE quiche_server] got packet Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061
[2025-04-30T09:12:41.965950853Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=26 pn=8 
[2025-04-30T09:12:41.965964879Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=100204 blocks=[1..3] ecn_counts=None
[2025-04-30T09:12:41.965973065Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 1
[2025-04-30T09:12:41.965977403Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 2
[2025-04-30T09:12:41.965980268Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 3
[2025-04-30T09:12:41.965986059Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm PING mtu_probe=None
[2025-04-30T09:12:41.966017128Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea dropped epoch 0 state
[2025-04-30T09:12:41.966021035Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 75 bytes
[2025-04-30T09:12:41.966029020Z TRACE quiche_server] got 75 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:41.966032877Z TRACE quiche_server] got packet Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061
[2025-04-30T09:12:41.966048156Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=26 pn=9 
[2025-04-30T09:12:41.966054398Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=100226 blocks=[1..3] ecn_counts=None
[2025-04-30T09:12:41.966058215Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm PING mtu_probe=None
[2025-04-30T09:12:41.966067412Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 75 bytes
[2025-04-30T09:12:41.966072272Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.966094343Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1134 pn=4 
[2025-04-30T09:12:41.966098331Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm ACK delay=5 blocks=[8..9] ecn_counts=None
[2025-04-30T09:12:41.966101917Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=3222 len=1124
[2025-04-30T09:12:41.966113189Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.505131106s latest_rtt=835.056546ms srtt=835.056546ms min_rtt=835.056546ms rttvar=417.528273ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1200 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3434, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 3, rate_sample: RateSample { delivery_rate: 4111, is_app_limited: true, interval: 835.306838ms, delivered: 3434, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 833106482 }), send_elapsed: 0ns, ack_elapsed: 835.306838ms, rtt: 835.306838ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 20208, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:41.966177119Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:41.966188200Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.966196546Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.966213407Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1134 pn=5 
[2025-04-30T09:12:41.966219669Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=4346 len=1129
[2025-04-30T09:12:41.966229368Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.505141034s latest_rtt=835.056546ms srtt=835.056546ms min_rtt=835.056546ms rttvar=417.528273ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3434, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { delivery_rate: 4111, is_app_limited: true, interval: 835.306838ms, delivered: 3434, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 833106482 }), send_elapsed: 0ns, ack_elapsed: 835.306838ms, rtt: 835.306838ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 20208, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:41.966280604Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:41.966290052Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.966297426Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.966315310Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1134 pn=6 
[2025-04-30T09:12:41.966320449Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=5475 len=1129
[2025-04-30T09:12:41.966329256Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.505141795s latest_rtt=835.056546ms srtt=835.056546ms min_rtt=835.056546ms rttvar=417.528273ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3434, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 3, rate_sample: RateSample { delivery_rate: 4111, is_app_limited: true, interval: 835.306838ms, delivered: 3434, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 833106482 }), send_elapsed: 0ns, ack_elapsed: 835.306838ms, rtt: 835.306838ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 20208, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:41.966371536Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:41.966380613Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.966387375Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.966403796Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1134 pn=7 
[2025-04-30T09:12:41.966409006Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=6604 len=1129
[2025-04-30T09:12:41.966417873Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.505143399s latest_rtt=835.056546ms srtt=835.056546ms min_rtt=835.056546ms rttvar=417.528273ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4800 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3434, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 3, rate_sample: RateSample { delivery_rate: 4111, is_app_limited: true, interval: 835.306838ms, delivered: 3434, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 833106482 }), send_elapsed: 0ns, ack_elapsed: 835.306838ms, rtt: 835.306838ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 20208, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:41.966473818Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:41.966483456Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.966490660Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.966508063Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1134 pn=8 
[2025-04-30T09:12:41.966512301Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=7733 len=1129
[2025-04-30T09:12:41.966521428Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.505142467s latest_rtt=835.056546ms srtt=835.056546ms min_rtt=835.056546ms rttvar=417.528273ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6000 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3434, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 3, rate_sample: RateSample { delivery_rate: 4111, is_app_limited: true, interval: 835.306838ms, delivered: 3434, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 833106482 }), send_elapsed: 0ns, ack_elapsed: 835.306838ms, rtt: 835.306838ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 20208, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:41.966591450Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:41.966606038Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.966613692Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.966630033Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1086 pn=9 
[2025-04-30T09:12:41.966633900Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=8862 len=1081
[2025-04-30T09:12:41.966642636Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.50514416s latest_rtt=835.056546ms srtt=835.056546ms min_rtt=835.056546ms rttvar=417.528273ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3434, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 3, rate_sample: RateSample { delivery_rate: 4111, is_app_limited: true, interval: 835.306838ms, delivered: 3434, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 833106482 }), send_elapsed: 0ns, ack_elapsed: 835.306838ms, rtt: 835.306838ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 20208, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:41.966692190Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 1152 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:41.966701387Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.966708280Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.966716406Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:41.966720734Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.997764391Z TRACE quiche_server] got 71 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:41.997776934Z TRACE quiche_server] got packet Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061
[2025-04-30T09:12:41.997786402Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=22 pn=10 
[2025-04-30T09:12:41.997794057Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=11 blocks=[4..4] ecn_counts=None
[2025-04-30T09:12:41.997798876Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 4
[2025-04-30T09:12:41.997815657Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 71 bytes
[2025-04-30T09:12:41.997820937Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.997826528Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:41.997829524Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.998592682Z TRACE quiche_server] got 71 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:41.998600076Z TRACE quiche_server] got packet Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061
[2025-04-30T09:12:41.998606488Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=22 pn=11 
[2025-04-30T09:12:41.998612249Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=4 blocks=[4..5] ecn_counts=None
[2025-04-30T09:12:41.998616116Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 5
[2025-04-30T09:12:41.998650220Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 71 bytes
[2025-04-30T09:12:41.998655230Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.998659718Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:41.998662563Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:41.999574472Z TRACE quiche_server] got 71 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:41.999580734Z TRACE quiche_server] got packet Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061
[2025-04-30T09:12:41.999586304Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=22 pn=12 
[2025-04-30T09:12:41.999591494Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=4 blocks=[4..6] ecn_counts=None
[2025-04-30T09:12:41.999598247Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 6
[2025-04-30T09:12:41.999608316Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 71 bytes
[2025-04-30T09:12:41.999611873Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:41.999615339Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:41.999617583Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.000528801Z TRACE quiche_server] got 71 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.000532318Z TRACE quiche_server] got packet Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061
[2025-04-30T09:12:42.000559819Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=22 pn=13 
[2025-04-30T09:12:42.000565320Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=5 blocks=[4..7] ecn_counts=None
[2025-04-30T09:12:42.000568325Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 7
[2025-04-30T09:12:42.000577673Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 71 bytes
[2025-04-30T09:12:42.000581430Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.000584917Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:42.000587171Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.001490433Z TRACE quiche_server] got 71 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.001493890Z TRACE quiche_server] got packet Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061
[2025-04-30T09:12:42.001498769Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=22 pn=14 
[2025-04-30T09:12:42.001503588Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=4 blocks=[4..8] ecn_counts=None
[2025-04-30T09:12:42.001506443Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 8
[2025-04-30T09:12:42.001514629Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 71 bytes
[2025-04-30T09:12:42.001517935Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.001520841Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:42.001523065Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.003500351Z TRACE quiche_server] got 454 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.003506913Z TRACE quiche_server] got packet Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061
[2025-04-30T09:12:42.003512203Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=63 pn=15 
[2025-04-30T09:12:42.003517524Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=86 blocks=[4..9] ecn_counts=None
[2025-04-30T09:12:42.003520599Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 9
[2025-04-30T09:12:42.003523856Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm CRYPTO off=0 len=36
[2025-04-30T09:12:42.003553341Z TRACE quiche::tls] c158c038dbb906457189d8a09cd0ef2f67adbbea set read secret lvl=OneRTT
[2025-04-30T09:12:42.003587616Z TRACE quiche::tls] c158c038dbb906457189d8a09cd0ef2f67adbbea write message lvl=OneRTT len=396
[2025-04-30T09:12:42.003595651Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea dropped epoch 1 state
[2025-04-30T09:12:42.003599358Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1350, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(3c4caa78f50e75205f3181def2204a9aa1718061), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T09:12:42.003625146Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=321 pn=16 
[2025-04-30T09:12:42.003632109Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[9f, a4, a8, f9, 14, 76, 7a, df, a4, 24, 39, ac, 78, 1a, 88, 0a, 5b, 84, 57, 88] reset_token=[f4, 40, 84, af, c0, 4f, e5, dd, 1e, ad, 85, 75, 94, 4b, da, b9]
[2025-04-30T09:12:42.003639854Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[8d, 4e, f2, 37, 74, 06, 64, 80, d4, a7, 68, d6, 5e, 8d, db, f3, bf, 3c, 0a, 0f] reset_token=[2c, d5, e1, 8c, 9e, 51, db, 57, d4, 06, 57, 17, 1b, 88, 3d, f6]
[2025-04-30T09:12:42.003645805Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[e1, b6, 58, ec, 2a, 32, 6f, 05, 5c, 5d, d0, 64, 1b, 8e, 90, aa, 20, c1, 29, 15] reset_token=[74, 70, 98, da, c5, 51, 23, 5b, 33, c9, e2, 8e, a0, 61, b4, fa]
[2025-04-30T09:12:42.003651306Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[93, 15, 2e, 6e, 9b, 5f, d5, 2c, 19, 76, 98, 78, bb, 82, 8a, a7, e0, 02, 32, 93] reset_token=[dc, 96, 8b, 22, 73, 3b, e0, 16, 6c, 73, 51, 9a, ba, 5e, 1c, be]
[2025-04-30T09:12:42.003657367Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[cf, c3, fc, b1, 68, fa, 8d, fa, 10, 99, 93, 10, 0c, 2e, cc, 06, 62, f8, 4b, 4b] reset_token=[9e, da, 93, 6f, c3, 9e, 41, 9e, 98, 3c, 82, cd, de, 5a, 10, 34]
[2025-04-30T09:12:42.003663018Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[cd, 7e, ed, 02, 99, ad, 5b, 76, 73, e2, f0, 8f, fb, f4, 59, 25, ec, b5, c9, a4] reset_token=[55, c2, 6f, 45, 30, c2, c3, af, f0, 73, a1, 42, ad, 7f, 1a, d4]
[2025-04-30T09:12:42.003668678Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[83, 40, 68, 33, 38, f1, e1, af, b5, 6f, b2, 28, 3e, 00, c2, d4, 80, 7e, bf, 2c] reset_token=[0f, 25, 83, 11, ad, 75, 6d, 6f, d4, cc, f2, 2d, d4, ab, 3a, da]
[2025-04-30T09:12:42.003673257Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T09:12:42.003683747Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 454 bytes
[2025-04-30T09:12:42.003696281Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm SETTINGS stream=3 len=18
[2025-04-30T09:12:42.003710287Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea open GREASE stream 15
[2025-04-30T09:12:42.003715266Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea stream id 2 is readable
[2025-04-30T09:12:42.003720426Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 2
[2025-04-30T09:12:42.003724153Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea open peer's control stream 2
[2025-04-30T09:12:42.003727689Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 2
[2025-04-30T09:12:42.003730835Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 2
[2025-04-30T09:12:42.003737869Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 16 bytes on stream 2
[2025-04-30T09:12:42.003740764Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(2535567138241663163, 3973116399000347820)]), additional_settings=Some([(2535567138241663163, 3973116399000347820)]) stream=2 payload_len=16
[2025-04-30T09:12:42.003750813Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.003754079Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.003758518Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=23 pn=17 
[2025-04-30T09:12:42.003762686Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T09:12:42.003767625Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 44 bytes
[2025-04-30T09:12:42.003770330Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea stream id 6 is readable
[2025-04-30T09:12:42.003773736Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 6
[2025-04-30T09:12:42.003779167Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.003781852Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.003785368Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=23 pn=18 
[2025-04-30T09:12:42.003789005Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T09:12:42.003794806Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 44 bytes
[2025-04-30T09:12:42.003797992Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea stream id 10 is readable
[2025-04-30T09:12:42.003801990Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 10
[2025-04-30T09:12:42.003808312Z TRACE quiche_server] got 110 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.003811718Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.003816096Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=89 pn=19 
[2025-04-30T09:12:42.003820815Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm STREAM id=0 off=0 len=67 fin=true
[2025-04-30T09:12:42.003827197Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 110 bytes
[2025-04-30T09:12:42.003830453Z DEBUG quiche_apps::common] c158c038dbb906457189d8a09cd0ef2f67adbbea response stream 0 is writable with capacity Ok(13500)
[2025-04-30T09:12:42.003834501Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea stream id 0 is readable
[2025-04-30T09:12:42.003838739Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 0
[2025-04-30T09:12:42.003854709Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 7 bytes on stream 0
[2025-04-30T09:12:42.003858777Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 0
[2025-04-30T09:12:42.003861933Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm UNKNOWN raw_type=3544035210028615165 stream=0 payload_len=0
[2025-04-30T09:12:42.003867523Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 0
[2025-04-30T09:12:42.003871501Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 7 bytes on stream 0
[2025-04-30T09:12:42.003875208Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 0
[2025-04-30T09:12:42.003882441Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 18 bytes on stream 0
[2025-04-30T09:12:42.003885517Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm UNKNOWN raw_type=2145482734441370431 stream=0 payload_len=18
[2025-04-30T09:12:42.003890436Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 0
[2025-04-30T09:12:42.003894294Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 0
[2025-04-30T09:12:42.003898231Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 29 bytes on stream 0
[2025-04-30T09:12:42.003901237Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm HEADERS stream=0 payload_len=29
[2025-04-30T09:12:42.003904723Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T09:12:42.003907869Z TRACE quiche::h3::qpack::decoder] Indexed index=17 static=true
[2025-04-30T09:12:42.003911045Z TRACE quiche::h3::qpack::decoder] Indexed index=23 static=true
[2025-04-30T09:12:42.003915263Z TRACE quiche::h3::qpack::decoder] Literal name_idx=0 static=true value=[115, 101, 114, 118, 101, 114, 52]
[2025-04-30T09:12:42.003919882Z TRACE quiche::h3::qpack::decoder] Literal name_idx=1 static=true value=[47, 103, 119, 101, 115, 115, 99, 105, 110, 103, 99]
[2025-04-30T09:12:42.003924040Z TRACE quiche::h3::qpack::decoder] Literal name_idx=95 static=true value=[113, 117, 105, 99, 104, 101]
[2025-04-30T09:12:42.003933477Z INFO  quiche_apps::common] c158c038dbb906457189d8a09cd0ef2f67adbbea got request [(":method", "GET"), (":scheme", "https"), (":authority", "server4"), (":path", "/gwesscingc"), ("user-agent", "quiche")] on stream id 0
[2025-04-30T09:12:42.003979254Z INFO  quiche_apps::common] c158c038dbb906457189d8a09cd0ef2f67adbbea prioritizing response on stream 0 as Priority { urgency: 3, incremental: false }
[2025-04-30T09:12:42.003988942Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm GREASE stream=0 len=0
[2025-04-30T09:12:42.003996416Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm GREASE stream=0 len=18
[2025-04-30T09:12:42.004004591Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm HEADERS stream=0 len=16 fin=false
[2025-04-30T09:12:42.004012396Z DEBUG quiche_apps::common] c158c038dbb906457189d8a09cd0ef2f67adbbea response stream 0 is writable with capacity Ok(13446)
[2025-04-30T09:12:42.004019590Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm DATA stream=0 len=5120 fin=true
[2025-04-30T09:12:42.004027595Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.004031362Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.004036622Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=48 pn=20 
[2025-04-30T09:12:42.004042423Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T09:12:42.004049256Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 69 bytes
[2025-04-30T09:12:42.004052542Z TRACE quiche::h3] c158c038dbb906457189d8a09cd0ef2f67adbbea stream id 14 is readable
[2025-04-30T09:12:42.004056970Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 1 bytes on stream 14
[2025-04-30T09:12:42.004060928Z TRACE quiche::h3::stream] c158c038dbb906457189d8a09cd0ef2f67adbbea read 7 bytes on stream 14
[2025-04-30T09:12:42.004066949Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.004074694Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:12:42.004083821Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=710 pn=10 
[2025-04-30T09:12:42.004092327Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm ACK delay=4 blocks=[16..20] ecn_counts=None
[2025-04-30T09:12:42.004096355Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[08, 21, 6a, a1, cb, 20, 58, cb, ae, 3b, d7, fe, d2, 50, ad, b5, 82, 23, 61, 54] reset_token=[64, 1e, db, b3, 40, ab, 74, b6, c4, cb, c7, 8a, 5c, 1f, 3a, 31]
[2025-04-30T09:12:42.004103598Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[a0, 9b, 81, a6, 5c, 41, fa, 95, 60, 94, 2b, 7c, 11, 58, a3, 01, 5e, 80, e3, c3] reset_token=[2c, b5, 92, 66, 3f, 17, cf, 48, 66, a4, a0, 55, 6f, 86, 56, 4a]
[2025-04-30T09:12:42.004109720Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[64, 6c, 90, 61, 77, c7, 66, 41, 5a, 64, f1, c8, e7, c3, 8e, a0, be, c4, c3, 63] reset_token=[80, 75, 31, 2a, 6f, 71, ea, e1, 2f, 96, 38, c3, f0, 45, e4, a5]
[2025-04-30T09:12:42.004115701Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[74, 0d, 4e, ed, 3c, ee, 9e, 1d, be, b2, 2e, 57, 5f, 4d, da, 79, b4, 8e, c4, 2c] reset_token=[c6, d9, e3, 76, da, 38, ab, d5, 6e, e3, b4, 77, 2a, c3, dc, 7a]
[2025-04-30T09:12:42.004121702Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[9e, 32, 2c, 02, 97, bb, 91, 90, 0f, b0, 66, 4f, ef, 23, 7e, 88, d3, 22, 19, d6] reset_token=[f5, 14, 60, 77, 12, c0, 49, 62, 04, a3, 94, 93, 9a, fe, 1c, bd]
[2025-04-30T09:12:42.004127784Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ee, bc, 1c, 47, 89, 18, 5c, 5f, ab, d0, 41, 2b, 52, ff, 1d, cf, 9e, f1, bf, c7] reset_token=[49, 7b, c1, 10, 26, 90, 90, 2c, 05, 82, cc, 2c, b7, f8, 6e, b9]
[2025-04-30T09:12:42.004133675Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[64, e2, b4, cb, fd, e9, 46, ba, d6, b3, 06, 6f, cf, 38, c5, a2, 5d, 05, d0, 52] reset_token=[1d, 99, 12, c8, 9b, 71, 49, 02, 19, 9c, 09, 81, 8a, 29, ce, c8]
[2025-04-30T09:12:42.004139726Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm HANDSHAKE_DONE
[2025-04-30T09:12:42.004142481Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm CRYPTO off=0 len=396
[2025-04-30T09:12:42.004145357Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm STREAM id=3 off=0 len=19 fin=false
[2025-04-30T09:12:42.004159193Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.447714829s latest_rtt=37.436215ms srtt=393.747947ms min_rtt=31.709141ms rttvar=507.264208ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=748 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10586, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 9, rate_sample: RateSample { delivery_rate: 225550, is_app_limited: true, interval: 31.709141ms, delivered: 7152, prior_delivered: 3434, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 668542374 }), send_elapsed: 0ns, ack_elapsed: 31.709141ms, rtt: 31.709141ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 42857, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:42.004213395Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 748 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:42.004230237Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.004238572Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.004248121Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12752 out_len=1350 left=1312
[2025-04-30T09:12:42.004256336Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=6 pn=11 
[2025-04-30T09:12:42.004260333Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T09:12:42.004268789Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.447778259s latest_rtt=37.436215ms srtt=393.747947ms min_rtt=31.709141ms rttvar=507.264208ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=792 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10586, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 9, rate_sample: RateSample { delivery_rate: 225550, is_app_limited: true, interval: 31.709141ms, delivered: 7152, prior_delivered: 3434, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 668542374 }), send_elapsed: 0ns, ack_elapsed: 31.709141ms, rtt: 31.709141ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 42857, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:42.004310999Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 44 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:42.004320306Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.004326739Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.004336106Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12708 out_len=1350 left=1312
[2025-04-30T09:12:42.004343230Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=6 pn=12 
[2025-04-30T09:12:42.004348289Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T09:12:42.004356685Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.447779041s latest_rtt=37.436215ms srtt=393.747947ms min_rtt=31.709141ms rttvar=507.264208ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=836 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10586, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 9, rate_sample: RateSample { delivery_rate: 225550, is_app_limited: true, interval: 31.709141ms, delivered: 7152, prior_delivered: 3434, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 668542374 }), send_elapsed: 0ns, ack_elapsed: 31.709141ms, rtt: 31.709141ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 42857, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:42.004402512Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 44 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:42.004411819Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.004418852Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.004428080Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12664 out_len=1350 left=1312
[2025-04-30T09:12:42.004435744Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=1312 pn=13 
[2025-04-30T09:12:42.004440874Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm STREAM id=0 off=0 len=1307 fin=false
[2025-04-30T09:12:42.004449249Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.44777818s latest_rtt=37.436215ms srtt=393.747947ms min_rtt=31.709141ms rttvar=507.264208ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2186 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10586, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 9, rate_sample: RateSample { delivery_rate: 225550, is_app_limited: true, interval: 31.709141ms, delivered: 7152, prior_delivered: 3434, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 668542374 }), send_elapsed: 0ns, ack_elapsed: 31.709141ms, rtt: 31.709141ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 42857, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:42.004463486Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11314 out_len=1350 left=1312
[2025-04-30T09:12:42.004467254Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=1312 pn=14 
[2025-04-30T09:12:42.004470269Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm STREAM id=0 off=1307 len=1306 fin=false
[2025-04-30T09:12:42.004476551Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.44778917s latest_rtt=37.436215ms srtt=393.747947ms min_rtt=31.709141ms rttvar=507.264208ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3536 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10586, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 9, rate_sample: RateSample { delivery_rate: 225550, is_app_limited: true, interval: 31.709141ms, delivered: 7152, prior_delivered: 3434, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 668542374 }), send_elapsed: 0ns, ack_elapsed: 31.709141ms, rtt: 31.709141ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 42857, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:42.004494094Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9964 out_len=1350 left=1312
[2025-04-30T09:12:42.004498021Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=1312 pn=15 
[2025-04-30T09:12:42.004501077Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm STREAM id=0 off=2613 len=1306 fin=false
[2025-04-30T09:12:42.004506488Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.447790092s latest_rtt=37.436215ms srtt=393.747947ms min_rtt=31.709141ms rttvar=507.264208ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4886 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10586, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 9, rate_sample: RateSample { delivery_rate: 225550, is_app_limited: true, interval: 31.709141ms, delivered: 7152, prior_delivered: 3434, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 668542374 }), send_elapsed: 0ns, ack_elapsed: 31.709141ms, rtt: 31.709141ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 42857, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:42.004518510Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8614 out_len=1350 left=1312
[2025-04-30T09:12:42.004522267Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=1264 pn=16 
[2025-04-30T09:12:42.004525233Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm STREAM id=0 off=3919 len=1258 fin=true
[2025-04-30T09:12:42.004530723Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.447790602s latest_rtt=37.436215ms srtt=393.747947ms min_rtt=31.709141ms rttvar=507.264208ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6188 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10586, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 9, rate_sample: RateSample { delivery_rate: 225550, is_app_limited: true, interval: 31.709141ms, delivered: 7152, prior_delivered: 3434, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 668542374 }), send_elapsed: 0ns, ack_elapsed: 31.709141ms, rtt: 31.709141ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 42857, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:42.004668493Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 5352 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:42.004679984Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.004691236Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.004698299Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7312 out_len=1350 left=1312
[2025-04-30T09:12:42.004703379Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=31 pn=17 
[2025-04-30T09:12:42.004706735Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea tx frm STREAM id=15 off=0 len=26 fin=true
[2025-04-30T09:12:42.004715381Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea timer=2.447782917s latest_rtt=37.436215ms srtt=393.747947ms min_rtt=31.709141ms rttvar=507.264208ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6257 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10586, delivered_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 9, rate_sample: RateSample { delivery_rate: 225550, is_app_limited: true, interval: 31.709141ms, delivered: 7152, prior_delivered: 3434, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 668542374 }), send_elapsed: 0ns, ack_elapsed: 31.709141ms, rtt: 31.709141ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 42857, last_update: Instant { tv_sec: 370, tv_nsec: 668542374 }, next_time: Instant { tv_sec: 370, tv_nsec: 668542374 }, 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:42.004756929Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:46977, at: Instant { tv_sec: 370, tv_nsec: 668542374 } })
[2025-04-30T09:12:42.004765977Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.004772569Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.004779071Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:42.004783289Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.035412644Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.035422824Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.035430097Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=22 pn=21 
[2025-04-30T09:12:42.035437201Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=7 blocks=[10..12] ecn_counts=None
[2025-04-30T09:12:42.035441839Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 10
[2025-04-30T09:12:42.035445997Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 11
[2025-04-30T09:12:42.035449183Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 12
[2025-04-30T09:12:42.035465063Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 43 bytes
[2025-04-30T09:12:42.035471245Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.035475072Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:42.035478058Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.036526935Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.036532866Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.036555739Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=22 pn=22 
[2025-04-30T09:12:42.036566349Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=15 blocks=[10..13] ecn_counts=None
[2025-04-30T09:12:42.036569525Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 13
[2025-04-30T09:12:42.036578522Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 43 bytes
[2025-04-30T09:12:42.036582830Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.036585575Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:42.036587789Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.037549402Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.037557026Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.037561625Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=22 pn=23 
[2025-04-30T09:12:42.037566394Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=4 blocks=[10..14] ecn_counts=None
[2025-04-30T09:12:42.037569420Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 14
[2025-04-30T09:12:42.037577154Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 43 bytes
[2025-04-30T09:12:42.037581322Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.037583927Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:42.037586091Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.038641089Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.038646539Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.038650607Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=22 pn=24 
[2025-04-30T09:12:42.038654845Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm ACK delay=4 blocks=[10..15] ecn_counts=None
[2025-04-30T09:12:42.038657600Z TRACE quiche::recovery::congestion::recovery] c158c038dbb906457189d8a09cd0ef2f67adbbea packet newly acked 15
[2025-04-30T09:12:42.038664243Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 43 bytes
[2025-04-30T09:12:42.038667990Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.038670475Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:42.038672669Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:42.039747254Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:46977 to [::]:443
[2025-04-30T09:12:42.039752414Z TRACE quiche_server] got packet Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false
[2025-04-30T09:12:42.039756311Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=28 pn=25 
[2025-04-30T09:12:42.039760900Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea rx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T09:12:42.039766761Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea processed 49 bytes
[2025-04-30T09:12:42.039770438Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:42.039772632Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:42.039774786Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:46.837620143Z TRACE quiche_server] timed out
[2025-04-30T09:12:46.837656301Z TRACE quiche] c158c038dbb906457189d8a09cd0ef2f67adbbea draining timeout expired
[2025-04-30T09:12:46.837709591Z TRACE quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea done writing
[2025-04-30T09:12:46.837720041Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:46.837724259Z INFO  quiche_server] c158c038dbb906457189d8a09cd0ef2f67adbbea connection collected recv=19 sent=18 lost=0 retrans=0 sent_bytes=17009 recv_bytes=1780 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:46977 validation_state=Validated active=true recv=19 sent=18 lost=0 retrans=0 rtt=260.227544ms min_rtt=Some(31.709141ms) rttvar=334.3154ms cwnd=13500 sent_bytes=17009 recv_bytes=1780 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=70450]