[2025-09-12T09:15:48.140667365Z INFO  quiche_server] listening on [::]:443
[2025-09-12T09:15:48.140690228Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T09:15:48.140696911Z TRACE quiche_server] GSO detected: false
[2025-09-12T09:15:48.470207528Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38300 to [::]:443
[2025-09-12T09:15:48.470250488Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-09-12T09:15:48.470264334Z WARN  quiche_server] Doing version negotiation
[2025-09-12T09:15:48.470339605Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.224396325Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.224452741Z TRACE quiche_server] got packet Initial version=1 dcid=fc76363f04d1b285 scid=37d48de8dc9f5f9493d260c28ec45a51 token=
[2025-09-12T09:15:49.224472518Z DEBUG quiche_server] New connection: dcid=fc76363f04d1b285 scid=4f911e3a5a2c60c6992684b1e8bdceabbb49007c
[2025-09-12T09:15:49.224697490Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Initial version=1 dcid=fc76363f04d1b285 scid=37d48de8dc9f5f9493d260c28ec45a51 token= len=1218 pn=0 
[2025-09-12T09:15:49.224711506Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm CRYPTO off=0 len=287
[2025-09-12T09:15:49.224742605Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-09-12T09:15:49.224747193Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-09-12T09:15:49.224861819Z TRACE quiche::tls] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c write message lvl=Initial len=123
[2025-09-12T09:15:49.224898367Z TRACE quiche::tls] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c set write secret lvl=Handshake
[2025-09-12T09:15:49.224966705Z TRACE quiche::tls] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c write message lvl=Handshake len=698
[2025-09-12T09:15:49.224970172Z TRACE quiche::tls] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c set write secret lvl=OneRTT
[2025-09-12T09:15:49.224977736Z TRACE quiche::tls] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c set read secret lvl=Handshake
[2025-09-12T09:15:49.224999757Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=910
[2025-09-12T09:15:49.225019725Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 1252 bytes
[2025-09-12T09:15:49.225031066Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.225046775Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Initial version=1 dcid=37d48de8dc9f5f9493d260c28ec45a51 scid=4f911e3a5a2c60c6992684b1e8bdceabbb49007c len=132 pn=0 
[2025-09-12T09:15:49.225051083Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm ACK delay=45 blocks=[0..0] ecn_counts=None
[2025-09-12T09:15:49.225056093Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm CRYPTO off=0 len=123
[2025-09-12T09:15:49.225067304Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=998.966367ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=195 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 573, tv_nsec: 395605950 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 395605950 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 573, tv_nsec: 395605950 }, next_time: Instant { tv_sec: 573, tv_nsec: 395605950 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.225096880Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Handshake version=1 dcid=37d48de8dc9f5f9493d260c28ec45a51 scid=4f911e3a5a2c60c6992684b1e8bdceabbb49007c len=702 pn=1 
[2025-09-12T09:15:49.225099865Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm CRYPTO off=0 len=698
[2025-09-12T09:15:49.225105636Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=998.928556ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=959 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 573, tv_nsec: 395605950 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 395605950 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 573, tv_nsec: 395605950 }, next_time: Instant { tv_sec: 573, tv_nsec: 395605950 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.225156692Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 573, tv_nsec: 395605950 } })
[2025-09-12T09:15:49.225166951Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.225173664Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.225180486Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.225184173Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.260460034Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.260491233Z TRACE quiche_server] got packet Initial version=1 dcid=4f911e3a5a2c60c6992684b1e8bdceabbb49007c scid=37d48de8dc9f5f9493d260c28ec45a51 token=
[2025-09-12T09:15:49.260504969Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Initial version=1 dcid=4f911e3a5a2c60c6992684b1e8bdceabbb49007c scid=37d48de8dc9f5f9493d260c28ec45a51 token= len=36 pn=1 
[2025-09-12T09:15:49.260516741Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=1922 blocks=[0..0] ecn_counts=None
[2025-09-12T09:15:49.260523814Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 0
[2025-09-12T09:15:49.260529424Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=13
[2025-09-12T09:15:49.260551346Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Handshake version=1 dcid=4f911e3a5a2c60c6992684b1e8bdceabbb49007c scid=37d48de8dc9f5f9493d260c28ec45a51 len=1125 pn=0 
[2025-09-12T09:15:49.260557167Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=1922 blocks=[1..1] ecn_counts=None
[2025-09-12T09:15:49.260559942Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 1
[2025-09-12T09:15:49.260563829Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm CRYPTO off=0 len=36
[2025-09-12T09:15:49.260582544Z TRACE quiche::tls] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c set read secret lvl=OneRTT
[2025-09-12T09:15:49.260615496Z TRACE quiche::tls] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c write message lvl=OneRTT len=396
[2025-09-12T09:15:49.260625384Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c dropped epoch 1 state
[2025-09-12T09:15:49.260629272Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("P-256") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 300000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 3750000, initial_max_stream_data_bidi_local: 3750000, initial_max_stream_data_bidi_remote: 3750000, initial_max_stream_data_uni: 3750000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 3, initial_source_connection_id: Some(37d48de8dc9f5f9493d260c28ec45a51), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T09:15:49.260654329Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=1063
[2025-09-12T09:15:49.260666922Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c dropped epoch 0 state
[2025-09-12T09:15:49.260669808Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 1252 bytes
[2025-09-12T09:15:49.260677372Z TRACE quiche_server] got 61 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.260680528Z TRACE quiche_server] got packet Short dcid=4f911e3a5a2c60c6992684b1e8bdceabbb49007c key_phase=false
[2025-09-12T09:15:49.260684906Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=4f911e3a5a2c60c6992684b1e8bdceabbb49007c key_phase=false len=40 pn=0 
[2025-09-12T09:15:49.260689234Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm STREAM id=0 off=0 len=0 fin=false
[2025-09-12T09:15:49.260693773Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm STREAM id=0 off=0 len=17 fin=true
[2025-09-12T09:15:49.260699293Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 61 bytes
[2025-09-12T09:15:49.260701968Z DEBUG quiche_apps::common] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c response stream 0 is writable with capacity Ok(13500)
[2025-09-12T09:15:49.260707438Z TRACE quiche_apps::common] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c received 17 bytes
[2025-09-12T09:15:49.260709903Z TRACE quiche_apps::common] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c stream 0 has 17 bytes (fin? true)
[2025-09-12T09:15:49.260714652Z INFO  quiche_apps::common] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c got GET request for "/www/gxcnfyxvpl" on stream 0
[2025-09-12T09:15:49.260753625Z INFO  quiche_apps::common] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c sending response of size 5120 on stream 0
[2025-09-12T09:15:49.260764706Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.260779494Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=37d48de8dc9f5f9493d260c28ec45a51 key_phase=false len=1316 pn=2 
[2025-09-12T09:15:49.260782619Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm ACK delay=10 blocks=[0..0] ecn_counts=None
[2025-09-12T09:15:49.260785916Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e4, 1d, 8e, 8e, 9c, 82, 06, 1d, 77, 61, ba, 5b, 58, 37, 43, 48, 9e, 43, 95, f5] reset_token=[9c, e2, fe, 21, c8, 64, 82, 81, fd, 19, 2b, c5, 82, e3, 19, 75]
[2025-09-12T09:15:49.260792498Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[6b, 52, a7, 3b, 89, 58, 8b, cd, d7, 15, 45, fd, 6d, bb, c6, ee, 14, f4, 95, af] reset_token=[b4, 8c, e0, 18, 32, 44, 7d, 8e, bc, 29, 3c, 94, bc, a7, 64, 7d]
[2025-09-12T09:15:49.260797477Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm HANDSHAKE_DONE
[2025-09-12T09:15:49.260799682Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm CRYPTO off=0 len=396
[2025-09-12T09:15:49.260802006Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm STREAM id=0 off=0 len=825 fin=false
[2025-09-12T09:15:49.260813157Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=113.676757ms latest_rtt=35.516272ms srtt=35.473061ms min_rtt=35.466889ms rttvar=13.312428ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 959, delivered_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { bandwidth: 216.01 Kbps, is_app_limited: true, interval: 35.516272ms, delivered: 959, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 395605950 }), send_elapsed: 0ns, ack_elapsed: 35.516272ms, rtt: 35.516272ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 475713, last_update: Instant { tv_sec: 573, tv_nsec: 431339099 }, next_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.260837603Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=37d48de8dc9f5f9493d260c28ec45a51 key_phase=false len=1316 pn=3 
[2025-09-12T09:15:49.260840418Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm STREAM id=0 off=825 len=1310 fin=false
[2025-09-12T09:15:49.260845888Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=113.711372ms latest_rtt=35.516272ms srtt=35.473061ms min_rtt=35.466889ms rttvar=13.312428ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2700 app_limited=true congestion_recovery_start_time=None Rate { delivered: 959, delivered_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { bandwidth: 216.01 Kbps, is_app_limited: true, interval: 35.516272ms, delivered: 959, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 395605950 }), send_elapsed: 0ns, ack_elapsed: 35.516272ms, rtt: 35.516272ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 475713, last_update: Instant { tv_sec: 573, tv_nsec: 431339099 }, next_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.260857430Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=37d48de8dc9f5f9493d260c28ec45a51 key_phase=false len=1316 pn=4 
[2025-09-12T09:15:49.260859955Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm STREAM id=0 off=2135 len=1310 fin=false
[2025-09-12T09:15:49.260864283Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=113.713936ms latest_rtt=35.516272ms srtt=35.473061ms min_rtt=35.466889ms rttvar=13.312428ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4050 app_limited=true congestion_recovery_start_time=None Rate { delivered: 959, delivered_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { bandwidth: 216.01 Kbps, is_app_limited: true, interval: 35.516272ms, delivered: 959, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 395605950 }), send_elapsed: 0ns, ack_elapsed: 35.516272ms, rtt: 35.516272ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 475713, last_update: Instant { tv_sec: 573, tv_nsec: 431339099 }, next_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.260877788Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=37d48de8dc9f5f9493d260c28ec45a51 key_phase=false len=1316 pn=5 
[2025-09-12T09:15:49.260880273Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm STREAM id=0 off=3445 len=1310 fin=false
[2025-09-12T09:15:49.260884591Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=113.714096ms latest_rtt=35.516272ms srtt=35.473061ms min_rtt=35.466889ms rttvar=13.312428ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 959, delivered_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { bandwidth: 216.01 Kbps, is_app_limited: true, interval: 35.516272ms, delivered: 959, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 395605950 }), send_elapsed: 0ns, ack_elapsed: 35.516272ms, rtt: 35.516272ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 475713, last_update: Instant { tv_sec: 573, tv_nsec: 431339099 }, next_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.260894770Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=37d48de8dc9f5f9493d260c28ec45a51 key_phase=false len=371 pn=6 
[2025-09-12T09:15:49.260897274Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm STREAM id=0 off=4755 len=365 fin=true
[2025-09-12T09:15:49.260902144Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=113.713535ms latest_rtt=35.516272ms srtt=35.473061ms min_rtt=35.466889ms rttvar=13.312428ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5805 app_limited=true congestion_recovery_start_time=None Rate { delivered: 959, delivered_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { bandwidth: 216.01 Kbps, is_app_limited: true, interval: 35.516272ms, delivered: 959, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 395605950 }), send_elapsed: 0ns, ack_elapsed: 35.516272ms, rtt: 35.516272ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 475713, last_update: Instant { tv_sec: 573, tv_nsec: 431339099 }, next_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.260991030Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c written 5805 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 573, tv_nsec: 431339099 } })
[2025-09-12T09:15:49.261000809Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.261008162Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.261012982Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.261022720Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.295556003Z TRACE quiche_server] got 1472 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.295585859Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.295600396Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=1451 pn=1 
[2025-09-12T09:15:49.295610495Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c path ID 0 now see SCID with seq num 1
[2025-09-12T09:15:49.295617048Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PING mtu_probe=None
[2025-09-12T09:15:49.295626415Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=1433
[2025-09-12T09:15:49.295641784Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 1472 bytes
[2025-09-12T09:15:49.295649398Z TRACE quiche_server] got 154 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.295653055Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.295657584Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=133 pn=2 
[2025-09-12T09:15:49.295662944Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=125 blocks=[2..2] ecn_counts=None
[2025-09-12T09:15:49.295669156Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 2
[2025-09-12T09:15:49.295679655Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=1 conn_id=[74, 47, 6a, b1, 00, e5, e4, 6b, af, 5a, 0d, 65, cb, 4e, 48, 5d] reset_token=[d4, 7b, 60, 81, 8b, 0c, 11, 2b, e7, 92, 89, 63, b5, 60, 01, 9c]
[2025-09-12T09:15:49.295688492Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c path ID 0 changed DCID: old seq num 0 new seq num 1
[2025-09-12T09:15:49.295693842Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=1 conn_id=[23, 57, 6b, a7, d5, 79, d2, 6c, e0, 63, d6, 05, 14, 9a, 4c, 5c] reset_token=[87, 00, ca, 79, 63, d6, 01, 78, de, 5b, 33, dd, 0e, d0, 7a, 5c]
[2025-09-12T09:15:49.295701236Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[fb, 3a, af, e0, c9, 04, e8, bc, d4, 9d, f3, fc, c3, 0b, 05, ab] reset_token=[54, 07, c2, 17, 70, 99, 59, 04, 70, 85, e9, d6, f2, e6, b0, 7a]
[2025-09-12T09:15:49.295706466Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-09-12T09:15:49.295752632Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 154 bytes
[2025-09-12T09:15:49.295756369Z INFO  quiche_server] Retiring source CID 4f911e3a5a2c60c6992684b1e8bdceabbb49007c
[2025-09-12T09:15:49.295766538Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.295781877Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=74476ab100e5e46baf5a0d65cb4e485d key_phase=false len=47 pn=7 
[2025-09-12T09:15:49.295785814Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm ACK delay=14 blocks=[1..2] ecn_counts=None
[2025-09-12T09:15:49.295789932Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[6d, 41, 69, eb, 8a, 83, 0c, e7, f2, c4, 97, 0a, 83, c1, dd, f5, 3d, 19, 45, 24] reset_token=[d2, 63, 54, fe, c3, ba, de, 50, fd, 35, 85, 98, 02, 11, b4, 04]
[2025-09-12T09:15:49.295796985Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm RETIRE_CONNECTION_ID seq_num=0
[2025-09-12T09:15:49.295807014Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=100.883353ms latest_rtt=34.889471ms srtt=35.400111ms min_rtt=34.889471ms rttvar=10.130218ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4536 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2309, delivered_time: Instant { tv_sec: 573, tv_nsec: 466228570 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 431339099 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 2, rate_sample: RateSample { bandwidth: 309.54 Kbps, is_app_limited: true, interval: 34.889471ms, delivered: 1350, prior_delivered: 959, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 431339099 }), send_elapsed: 0ns, ack_elapsed: 34.889471ms, rtt: 34.889471ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 476693, last_update: Instant { tv_sec: 573, tv_nsec: 466341462 }, next_time: Instant { tv_sec: 573, tv_nsec: 466341462 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.295874220Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c written 81 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 573, tv_nsec: 466341462 } })
[2025-09-12T09:15:49.295881995Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.295887916Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.295891903Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.295896772Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.321411396Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.321448275Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.321460438Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=3 
[2025-09-12T09:15:49.321472040Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=3103 blocks=[2..6] ecn_counts=None
[2025-09-12T09:15:49.321478252Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 3
[2025-09-12T09:15:49.321481437Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 4
[2025-09-12T09:15:49.321483742Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 5
[2025-09-12T09:15:49.321486006Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 6
[2025-09-12T09:15:49.321491597Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=13
[2025-09-12T09:15:49.321514349Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.321520531Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.321525961Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.321528716Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.352254445Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.352284492Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.352298147Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=5 
[2025-09-12T09:15:49.352311322Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=3158 blocks=[3..7] ecn_counts=None
[2025-09-12T09:15:49.352318716Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 7
[2025-09-12T09:15:49.352326140Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=13
[2025-09-12T09:15:49.352344595Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.352362338Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.352368840Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.352372126Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.457015970Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.457043462Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.457056266Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=7 
[2025-09-12T09:15:49.457068369Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=16228 blocks=[3..7] ecn_counts=None
[2025-09-12T09:15:49.457076033Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PING mtu_probe=None
[2025-09-12T09:15:49.457079159Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=12
[2025-09-12T09:15:49.457093906Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.457100389Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.457103394Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.457107111Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=9 
[2025-09-12T09:15:49.457111009Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=16228 blocks=[3..7] ecn_counts=None
[2025-09-12T09:15:49.457113934Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PING mtu_probe=None
[2025-09-12T09:15:49.457116298Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=12
[2025-09-12T09:15:49.457120737Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.457124574Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.457139282Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=74476ab100e5e46baf5a0d65cb4e485d key_phase=false len=11 pn=8 
[2025-09-12T09:15:49.457142417Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm ACK delay=3 blocks=[3..3, 5..5, 7..7, 9..9] ecn_counts=None
[2025-09-12T09:15:49.457151525Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=none latest_rtt=56.524987ms srtt=38.091678ms min_rtt=34.889471ms rttvar=11.052264ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 6845, delivered_time: Instant { tv_sec: 573, tv_nsec: 627698827 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 627698827 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 7, rate_sample: RateSample { bandwidth: 765.19 Kbps, is_app_limited: true, interval: 56.637879ms, delivered: 4536, prior_delivered: 2309, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 466228570 }), send_elapsed: 35.002363ms, ack_elapsed: 56.637879ms, rtt: 56.524987ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 443010, last_update: Instant { tv_sec: 573, tv_nsec: 627698827 }, next_time: Instant { tv_sec: 573, tv_nsec: 627698827 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.457207680Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c written 45 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 573, tv_nsec: 627698827 } })
[2025-09-12T09:15:49.457213972Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.457231324Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.457234390Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.457236664Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.608245057Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.608275113Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.608289651Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=12 
[2025-09-12T09:15:49.608303116Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=14942 blocks=[8..8] ecn_counts=None
[2025-09-12T09:15:49.608310690Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 8
[2025-09-12T09:15:49.608316401Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PING mtu_probe=None
[2025-09-12T09:15:49.608320288Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=12
[2025-09-12T09:15:49.608339274Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.608346988Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.608350876Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.608355484Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=14 
[2025-09-12T09:15:49.608360323Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=14942 blocks=[8..8] ecn_counts=None
[2025-09-12T09:15:49.608364020Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PING mtu_probe=None
[2025-09-12T09:15:49.608366906Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=12
[2025-09-12T09:15:49.608372165Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.608376804Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.608391882Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=74476ab100e5e46baf5a0d65cb4e485d key_phase=false len=7 pn=9 
[2025-09-12T09:15:49.608395660Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm ACK delay=4 blocks=[12..12, 14..14] ecn_counts=None
[2025-09-12T09:15:49.608405828Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=none latest_rtt=56.524987ms srtt=38.091678ms min_rtt=34.889471ms rttvar=11.052264ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 6845, delivered_time: Instant { tv_sec: 573, tv_nsec: 778951658 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 778951658 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 8, rate_sample: RateSample { bandwidth: 765.19 Kbps, is_app_limited: true, interval: 151.158784ms, delivered: 0, prior_delivered: 6845, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 627698827 }), send_elapsed: 0ns, ack_elapsed: 151.158784ms, rtt: 151.158784ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 443010, last_update: Instant { tv_sec: 573, tv_nsec: 778951658 }, next_time: Instant { tv_sec: 573, tv_nsec: 778951658 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.608485959Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c written 41 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 573, tv_nsec: 778951658 } })
[2025-09-12T09:15:49.608506858Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.608514412Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.608520724Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.608525533Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.750606506Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.750645159Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.750660287Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=17 
[2025-09-12T09:15:49.750673983Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=13820 blocks=[9..9] ecn_counts=None
[2025-09-12T09:15:49.750681377Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c packet newly acked 9
[2025-09-12T09:15:49.750687799Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PING mtu_probe=None
[2025-09-12T09:15:49.750691516Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=12
[2025-09-12T09:15:49.750709009Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.750717955Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.750721843Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.750726511Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=19 
[2025-09-12T09:15:49.750733745Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm ACK delay=13820 blocks=[9..9] ecn_counts=None
[2025-09-12T09:15:49.750737542Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PING mtu_probe=None
[2025-09-12T09:15:49.750740508Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=12
[2025-09-12T09:15:49.750745878Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.750750576Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.750765354Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx pkt Short dcid=74476ab100e5e46baf5a0d65cb4e485d key_phase=false len=7 pn=10 
[2025-09-12T09:15:49.750769141Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c tx frm ACK delay=4 blocks=[17..17, 19..19] ecn_counts=None
[2025-09-12T09:15:49.750778980Z TRACE quiche::recovery::congestion::recovery] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c timer=none latest_rtt=56.524987ms srtt=38.091678ms min_rtt=34.889471ms rttvar=11.052264ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 6845, delivered_time: Instant { tv_sec: 573, tv_nsec: 921325460 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 921325460 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 9, rate_sample: RateSample { bandwidth: 765.19 Kbps, is_app_limited: true, interval: 142.276389ms, delivered: 0, prior_delivered: 6845, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 778951658 }), send_elapsed: 0ns, ack_elapsed: 142.276389ms, rtt: 142.276389ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 443010, last_update: Instant { tv_sec: 573, tv_nsec: 921325460 }, next_time: Instant { tv_sec: 573, tv_nsec: 921325460 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.750850755Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c written 41 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 573, tv_nsec: 921325460 } })
[2025-09-12T09:15:49.750873137Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.750879358Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.750883325Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.750886191Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.798543293Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.798576976Z TRACE quiche_server] got packet Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false
[2025-09-12T09:15:49.798591223Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx pkt Short dcid=e41d8e8e9c82061d7761ba5b583743489e4395f5 key_phase=false len=36 pn=21 
[2025-09-12T09:15:49.798604187Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-09-12T09:15:49.798610178Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c rx frm PADDING len=15
[2025-09-12T09:15:49.798626789Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c processed 57 bytes
[2025-09-12T09:15:49.798634093Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.798637770Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.798641236Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.800458305Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.800470167Z TRACE quiche_server] got packet Initial version=1 dcid=dcef0fc2f013a01a scid=caad3e9153056274ce3719e92f7d2004 token=
[2025-09-12T09:15:49.800476279Z DEBUG quiche_server] New connection: dcid=dcef0fc2f013a01a scid=1b2849e2b10914a669354001487ffa2f5dcfe56d
[2025-09-12T09:15:49.800641519Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Initial version=1 dcid=dcef0fc2f013a01a scid=caad3e9153056274ce3719e92f7d2004 token= len=1218 pn=0 
[2025-09-12T09:15:49.800653121Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm CRYPTO off=0 len=502
[2025-09-12T09:15:49.800680522Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-09-12T09:15:49.800684980Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-09-12T09:15:49.800816607Z TRACE quiche::tls] 1b2849e2b10914a669354001487ffa2f5dcfe56d write message lvl=Initial len=129
[2025-09-12T09:15:49.800835292Z TRACE quiche::tls] 1b2849e2b10914a669354001487ffa2f5dcfe56d set write secret lvl=Handshake
[2025-09-12T09:15:49.800864507Z TRACE quiche::tls] 1b2849e2b10914a669354001487ffa2f5dcfe56d write message lvl=Handshake len=148
[2025-09-12T09:15:49.800867563Z TRACE quiche::tls] 1b2849e2b10914a669354001487ffa2f5dcfe56d set write secret lvl=OneRTT
[2025-09-12T09:15:49.800874796Z TRACE quiche::tls] 1b2849e2b10914a669354001487ffa2f5dcfe56d set read secret lvl=Handshake
[2025-09-12T09:15:49.800889865Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=695
[2025-09-12T09:15:49.800905294Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 1252 bytes
[2025-09-12T09:15:49.800916154Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.800930741Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Initial version=1 dcid=caad3e9153056274ce3719e92f7d2004 scid=1b2849e2b10914a669354001487ffa2f5dcfe56d len=138 pn=0 
[2025-09-12T09:15:49.800934609Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm ACK delay=38 blocks=[0..0] ecn_counts=None
[2025-09-12T09:15:49.800939398Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm CRYPTO off=0 len=129
[2025-09-12T09:15:49.800949166Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=998.969171ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=201 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 573, tv_nsec: 971490476 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 971490476 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 573, tv_nsec: 971490476 }, next_time: Instant { tv_sec: 573, tv_nsec: 971490476 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.800984813Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Handshake version=1 dcid=caad3e9153056274ce3719e92f7d2004 scid=1b2849e2b10914a669354001487ffa2f5dcfe56d len=152 pn=1 
[2025-09-12T09:15:49.800988259Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm CRYPTO off=0 len=148
[2025-09-12T09:15:49.800994932Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=998.923656ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=415 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 573, tv_nsec: 971490476 }, first_sent_time: Instant { tv_sec: 573, tv_nsec: 971490476 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 573, tv_nsec: 971490476 }, next_time: Instant { tv_sec: 573, tv_nsec: 971490476 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.801054213Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 573, tv_nsec: 971490476 } })
[2025-09-12T09:15:49.801061366Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.801064242Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.801066826Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.801074040Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.801081364Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:49.801086133Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.801090421Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.801094378Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.835585681Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.835620085Z TRACE quiche_server] got packet Initial version=1 dcid=1b2849e2b10914a669354001487ffa2f5dcfe56d scid=caad3e9153056274ce3719e92f7d2004 token=
[2025-09-12T09:15:49.835634743Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Initial version=1 dcid=1b2849e2b10914a669354001487ffa2f5dcfe56d scid=caad3e9153056274ce3719e92f7d2004 token= len=36 pn=1 
[2025-09-12T09:15:49.835647367Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=1289 blocks=[0..0] ecn_counts=None
[2025-09-12T09:15:49.835655121Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 0
[2025-09-12T09:15:49.835675359Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=13
[2025-09-12T09:15:49.835700757Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Handshake version=1 dcid=1b2849e2b10914a669354001487ffa2f5dcfe56d scid=caad3e9153056274ce3719e92f7d2004 len=1125 pn=0 
[2025-09-12T09:15:49.835707369Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=1289 blocks=[1..1] ecn_counts=None
[2025-09-12T09:15:49.835710815Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 1
[2025-09-12T09:15:49.835714983Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm CRYPTO off=0 len=36
[2025-09-12T09:15:49.835735793Z TRACE quiche::tls] 1b2849e2b10914a669354001487ffa2f5dcfe56d set read secret lvl=OneRTT
[2025-09-12T09:15:49.835771660Z TRACE quiche::tls] 1b2849e2b10914a669354001487ffa2f5dcfe56d write message lvl=OneRTT len=396
[2025-09-12T09:15:49.835781719Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d dropped epoch 1 state
[2025-09-12T09:15:49.835786718Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("P-256") sigalg=None resumed=true TransportParams { original_destination_connection_id: None, max_idle_timeout: 300000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 3750000, initial_max_stream_data_bidi_local: 3750000, initial_max_stream_data_bidi_remote: 3750000, initial_max_stream_data_uni: 3750000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 3, initial_source_connection_id: Some(caad3e9153056274ce3719e92f7d2004), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T09:15:49.835801425Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=1063
[2025-09-12T09:15:49.835815923Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d dropped epoch 0 state
[2025-09-12T09:15:49.835819469Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 1252 bytes
[2025-09-12T09:15:49.835827715Z TRACE quiche_server] got 61 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.835831582Z TRACE quiche_server] got packet Short dcid=1b2849e2b10914a669354001487ffa2f5dcfe56d key_phase=false
[2025-09-12T09:15:49.835836662Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=1b2849e2b10914a669354001487ffa2f5dcfe56d key_phase=false len=40 pn=0 
[2025-09-12T09:15:49.835841952Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm STREAM id=0 off=0 len=0 fin=false
[2025-09-12T09:15:49.835847202Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm STREAM id=0 off=0 len=17 fin=true
[2025-09-12T09:15:49.835853794Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 61 bytes
[2025-09-12T09:15:49.835857140Z DEBUG quiche_apps::common] 1b2849e2b10914a669354001487ffa2f5dcfe56d response stream 0 is writable with capacity Ok(13500)
[2025-09-12T09:15:49.835863342Z TRACE quiche_apps::common] 1b2849e2b10914a669354001487ffa2f5dcfe56d received 17 bytes
[2025-09-12T09:15:49.835866327Z TRACE quiche_apps::common] 1b2849e2b10914a669354001487ffa2f5dcfe56d stream 0 has 17 bytes (fin? true)
[2025-09-12T09:15:49.835871357Z INFO  quiche_apps::common] 1b2849e2b10914a669354001487ffa2f5dcfe56d got GET request for "/www/yygihvcxon" on stream 0
[2025-09-12T09:15:49.835907785Z INFO  quiche_apps::common] 1b2849e2b10914a669354001487ffa2f5dcfe56d sending response of size 10240 on stream 0
[2025-09-12T09:15:49.835917032Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.835934154Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=1316 pn=2 
[2025-09-12T09:15:49.835937751Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm ACK delay=11 blocks=[0..0] ecn_counts=None
[2025-09-12T09:15:49.835947119Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[65, 7d, b9, 3e, d4, 71, 3f, c0, 7f, e3, d6, 86, d8, 29, d2, 05, fa, 5c, a1, 18] reset_token=[6f, a9, c7, 85, 7d, 3e, 4a, e0, ad, f7, e8, b0, 79, f7, 7c, e3]
[2025-09-12T09:15:49.835955204Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[c0, dc, cc, b1, 40, 96, 47, 77, 89, 86, c2, 50, 07, 7f, c5, 91, d1, 8f, eb, bb] reset_token=[bd, 69, 21, c1, 7f, 57, 4a, a0, 43, a9, 58, ea, 4d, 08, 07, 12]
[2025-09-12T09:15:49.835961636Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm HANDSHAKE_DONE
[2025-09-12T09:15:49.835964391Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm CRYPTO off=0 len=396
[2025-09-12T09:15:49.835967236Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=0 len=825 fin=false
[2025-09-12T09:15:49.835980281Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.797807ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836002773Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=1316 pn=3 
[2025-09-12T09:15:49.836006250Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=825 len=1310 fin=false
[2025-09-12T09:15:49.836012722Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.844514ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2700 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836026618Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=1316 pn=4 
[2025-09-12T09:15:49.836033952Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=2135 len=1310 fin=false
[2025-09-12T09:15:49.836039672Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.842541ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4050 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836052536Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=1316 pn=5 
[2025-09-12T09:15:49.836055622Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=3445 len=1310 fin=false
[2025-09-12T09:15:49.836060842Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.84744ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836073746Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=1316 pn=6 
[2025-09-12T09:15:49.836076852Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=4755 len=1310 fin=false
[2025-09-12T09:15:49.836082563Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.847209ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6750 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836099134Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=1316 pn=7 
[2025-09-12T09:15:49.836102260Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=6065 len=1310 fin=false
[2025-09-12T09:15:49.836107700Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.847219ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8100 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836120484Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=1316 pn=8 
[2025-09-12T09:15:49.836123560Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=7375 len=1310 fin=false
[2025-09-12T09:15:49.836128710Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.847591ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9450 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836141113Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=1316 pn=9 
[2025-09-12T09:15:49.836147535Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=8685 len=1310 fin=false
[2025-09-12T09:15:49.836152845Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.844224ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10800 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 3, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836165539Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=caad3e9153056274ce3719e92f7d2004 key_phase=false len=251 pn=10 
[2025-09-12T09:15:49.836168624Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm STREAM id=0 off=9995 len=245 fin=true
[2025-09-12T09:15:49.836199652Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=111.821782ms latest_rtt=34.781067ms srtt=34.720946ms min_rtt=34.712358ms rttvar=13.034311ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=11085 app_limited=true congestion_recovery_start_time=None Rate { delivered: 415, delivered_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 3, last_sent_packet: 10, largest_acked: 1, rate_sample: RateSample { bandwidth: 95.45 Kbps, is_app_limited: true, interval: 34.781067ms, delivered: 415, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 573, tv_nsec: 971490476 }), send_elapsed: 0ns, ack_elapsed: 34.781067ms, rtt: 34.781067ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 486017, last_update: Instant { tv_sec: 574, tv_nsec: 6491926 }, next_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.836343032Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d written 11085 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 574, tv_nsec: 6491926 } })
[2025-09-12T09:15:49.836353541Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.836357920Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.836361226Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.836366145Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.836369511Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:49.836371806Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.836373910Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.836375923Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.870733646Z TRACE quiche_server] got 1472 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.870769523Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:49.870784231Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=1451 pn=1 
[2025-09-12T09:15:49.870794640Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d path ID 0 now see SCID with seq num 1
[2025-09-12T09:15:49.870800872Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PING mtu_probe=None
[2025-09-12T09:15:49.870810310Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=1433
[2025-09-12T09:15:49.870826280Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 1472 bytes
[2025-09-12T09:15:49.870833623Z TRACE quiche_server] got 154 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.870837390Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:49.870841909Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=133 pn=2 
[2025-09-12T09:15:49.870847479Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=115 blocks=[2..2] ecn_counts=None
[2025-09-12T09:15:49.870853641Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 2
[2025-09-12T09:15:49.870865022Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=1 conn_id=[9a, 8f, 89, 60, af, 3f, c2, 39, 50, e1, de, 4f, 7e, b5, 9d, 56] reset_token=[99, 59, 1a, 2d, d1, 8a, 8d, 7c, 7a, 59, 15, 76, 63, d6, 29, 42]
[2025-09-12T09:15:49.870873538Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d path ID 0 changed DCID: old seq num 0 new seq num 1
[2025-09-12T09:15:49.870878908Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=1 conn_id=[23, ef, 06, 8d, 7e, 3a, 1e, e0, f8, 8d, 5e, 37, ba, e7, 55, b2] reset_token=[cf, db, 8c, 9a, 19, 09, 7a, 33, 3b, c8, 7d, fd, cd, 16, 96, 7a]
[2025-09-12T09:15:49.870885721Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[18, 60, ad, eb, cb, fd, 23, f9, 2a, db, a6, b2, 1d, e0, 40, 9e] reset_token=[46, 9e, de, 14, e6, d5, 41, d1, e3, f1, 1b, 23, 48, 2b, 49, 56]
[2025-09-12T09:15:49.870890921Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-09-12T09:15:49.870906620Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 154 bytes
[2025-09-12T09:15:49.870909886Z INFO  quiche_server] Retiring source CID 1b2849e2b10914a669354001487ffa2f5dcfe56d
[2025-09-12T09:15:49.870919785Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.870933641Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=9a8f8960af3fc23950e1de4f7eb59d56 key_phase=false len=47 pn=11 
[2025-09-12T09:15:49.870937518Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm ACK delay=10 blocks=[1..2] ecn_counts=None
[2025-09-12T09:15:49.870941716Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[fa, 54, 57, 85, 4c, 59, e4, ca, e9, e4, 3c, c5, e1, 49, 59, cf, c8, d7, 5e, 69] reset_token=[37, ac, c6, b0, 94, 0e, 09, 6a, 22, 07, 32, bf, 41, 38, 4e, bf]
[2025-09-12T09:15:49.870948699Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm RETIRE_CONNECTION_ID seq_num=0
[2025-09-12T09:15:49.870958347Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=99.013076ms latest_rtt=34.920979ms srtt=34.745949ms min_rtt=34.712358ms rttvar=9.825741ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9816 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1765, delivered_time: Instant { tv_sec: 574, tv_nsec: 41412905 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 6491926 }, end_of_app_limited: 3, last_sent_packet: 11, largest_acked: 2, rate_sample: RateSample { bandwidth: 309.26 Kbps, is_app_limited: true, interval: 34.920979ms, delivered: 1350, prior_delivered: 415, prior_time: Some(Instant { tv_sec: 574, tv_nsec: 6491926 }), send_elapsed: 0ns, ack_elapsed: 34.920979ms, rtt: 34.920979ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 485668, last_update: Instant { tv_sec: 574, tv_nsec: 41494609 }, next_time: Instant { tv_sec: 574, tv_nsec: 41494609 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:49.871037426Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d written 81 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 574, tv_nsec: 41494609 } })
[2025-09-12T09:15:49.871048226Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.871052814Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.871056782Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.871064767Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.871070648Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:49.871075657Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.871079795Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.871083342Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.896531871Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.896557379Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:49.896570283Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=3 
[2025-09-12T09:15:49.896581875Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=2529 blocks=[2..10] ecn_counts=None
[2025-09-12T09:15:49.896588567Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 3
[2025-09-12T09:15:49.896591443Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 4
[2025-09-12T09:15:49.896593777Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 5
[2025-09-12T09:15:49.896595911Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 6
[2025-09-12T09:15:49.896599087Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 7
[2025-09-12T09:15:49.896603175Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 8
[2025-09-12T09:15:49.896605379Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 9
[2025-09-12T09:15:49.896607483Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 10
[2025-09-12T09:15:49.896613043Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=13
[2025-09-12T09:15:49.896639473Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:49.896645795Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.896651225Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:49.896653950Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.896656745Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.896670972Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.928225345Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:49.928247286Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:49.928259059Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=5 
[2025-09-12T09:15:49.928270710Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=3277 blocks=[3..11] ecn_counts=None
[2025-09-12T09:15:49.928278064Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 11
[2025-09-12T09:15:49.928284847Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=13
[2025-09-12T09:15:49.928299925Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:49.928306858Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:49.928312348Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:49.928315595Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:49.928318630Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:49.928321365Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.013642311Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:50.013672187Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:50.013686935Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=7 
[2025-09-12T09:15:50.013700540Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=13904 blocks=[3..11] ecn_counts=None
[2025-09-12T09:15:50.013709277Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PING mtu_probe=None
[2025-09-12T09:15:50.013712893Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=12
[2025-09-12T09:15:50.013728693Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:50.013736337Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:50.013740064Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:50.013744653Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=9 
[2025-09-12T09:15:50.013749612Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=13904 blocks=[3..11] ecn_counts=None
[2025-09-12T09:15:50.013753359Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PING mtu_probe=None
[2025-09-12T09:15:50.013756275Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=12
[2025-09-12T09:15:50.013762957Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:50.013770922Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:50.013788085Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=9a8f8960af3fc23950e1de4f7eb59d56 key_phase=false len=11 pn=12 
[2025-09-12T09:15:50.013791841Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm ACK delay=4 blocks=[3..3, 5..5, 7..7, 9..9] ecn_counts=None
[2025-09-12T09:15:50.013802732Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=none latest_rtt=57.332971ms srtt=38.18935ms min_rtt=34.712358ms rttvar=12.059483ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 11581, delivered_time: Instant { tv_sec: 574, tv_nsec: 184346487 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 184346487 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 11, rate_sample: RateSample { bandwidth: 1.46 Mbps, is_app_limited: true, interval: 57.414675ms, delivered: 9816, prior_delivered: 1765, prior_time: Some(Instant { tv_sec: 574, tv_nsec: 41412905 }), send_elapsed: 35.002683ms, ack_elapsed: 57.414675ms, rtt: 57.332971ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 441877, last_update: Instant { tv_sec: 574, tv_nsec: 184346487 }, next_time: Instant { tv_sec: 574, tv_nsec: 184346487 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:50.013884295Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d written 45 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 574, tv_nsec: 184346487 } })
[2025-09-12T09:15:50.013891739Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:50.013895266Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.013898071Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.013902940Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:50.013906547Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:50.013909332Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:50.013911977Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.013914451Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.046002807Z TRACE quiche_server] timed out
[2025-09-12T09:15:50.046032442Z TRACE quiche] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c draining timeout expired
[2025-09-12T09:15:50.046082867Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:50.046086935Z TRACE quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c done writing
[2025-09-12T09:15:50.046090030Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.046092916Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.046098025Z INFO  quiche_server] 4f911e3a5a2c60c6992684b1e8bdceabbb49007c connection collected recv=15 sent=11 lost=0 retrans=0 sent_bytes=6972 recv_bytes=4704 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:55493 validation_state=Validated active=true recv=15 sent=11 lost=0 retrans=0 rtt=38.091678ms min_rtt=Some(34.889471ms) rttvar=11.052264ms cwnd=13500 sent_bytes=6972 recv_bytes=4704 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=95649]
[2025-09-12T09:15:50.144396107Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:50.144423178Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:50.144457683Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=12 
[2025-09-12T09:15:50.144471879Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=12378 blocks=[12..12] ecn_counts=None
[2025-09-12T09:15:50.144479203Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 12
[2025-09-12T09:15:50.144485204Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PING mtu_probe=None
[2025-09-12T09:15:50.144489002Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=12
[2025-09-12T09:15:50.144516042Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:50.144524047Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:50.144528085Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:50.144532944Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=14 
[2025-09-12T09:15:50.144552831Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=12378 blocks=[12..12] ecn_counts=None
[2025-09-12T09:15:50.144556819Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PING mtu_probe=None
[2025-09-12T09:15:50.144559764Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=12
[2025-09-12T09:15:50.144565385Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:50.144570124Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:50.144584461Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=9a8f8960af3fc23950e1de4f7eb59d56 key_phase=false len=7 pn=13 
[2025-09-12T09:15:50.144588128Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm ACK delay=5 blocks=[12..12, 14..14] ecn_counts=None
[2025-09-12T09:15:50.144597976Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=none latest_rtt=57.332971ms srtt=38.18935ms min_rtt=34.712358ms rttvar=12.059483ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 11581, delivered_time: Instant { tv_sec: 574, tv_nsec: 315144977 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 315144977 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 12, rate_sample: RateSample { bandwidth: 1.46 Mbps, is_app_limited: true, interval: 130.678996ms, delivered: 0, prior_delivered: 11581, prior_time: Some(Instant { tv_sec: 574, tv_nsec: 184346487 }), send_elapsed: 0ns, ack_elapsed: 130.678996ms, rtt: 130.678996ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 441877, last_update: Instant { tv_sec: 574, tv_nsec: 315144977 }, next_time: Instant { tv_sec: 574, tv_nsec: 315144977 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:50.144657107Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d written 41 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 574, tv_nsec: 315144977 } })
[2025-09-12T09:15:50.144664320Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.144670282Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:50.144674019Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:50.144676794Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.267804542Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:50.267835480Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:50.267849656Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=17 
[2025-09-12T09:15:50.267863122Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=11448 blocks=[13..13] ecn_counts=None
[2025-09-12T09:15:50.267871237Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d packet newly acked 13
[2025-09-12T09:15:50.267877288Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PING mtu_probe=None
[2025-09-12T09:15:50.267881135Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=12
[2025-09-12T09:15:50.267903097Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:50.267913456Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:50.267917353Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:50.267933323Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=19 
[2025-09-12T09:15:50.267938403Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm ACK delay=11448 blocks=[13..13] ecn_counts=None
[2025-09-12T09:15:50.267942210Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PING mtu_probe=None
[2025-09-12T09:15:50.267945135Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=12
[2025-09-12T09:15:50.267950736Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:50.267955565Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:50.267970272Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx pkt Short dcid=9a8f8960af3fc23950e1de4f7eb59d56 key_phase=false len=7 pn=14 
[2025-09-12T09:15:50.267974019Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d tx frm ACK delay=4 blocks=[17..17, 19..19] ecn_counts=None
[2025-09-12T09:15:50.267983798Z TRACE quiche::recovery::congestion::recovery] 1b2849e2b10914a669354001487ffa2f5dcfe56d timer=none latest_rtt=57.332971ms srtt=38.18935ms min_rtt=34.712358ms rttvar=12.059483ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 11581, delivered_time: Instant { tv_sec: 574, tv_nsec: 438530429 }, first_sent_time: Instant { tv_sec: 574, tv_nsec: 438530429 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 13, rate_sample: RateSample { bandwidth: 1.46 Mbps, is_app_limited: true, interval: 123.272861ms, delivered: 0, prior_delivered: 11581, prior_time: Some(Instant { tv_sec: 574, tv_nsec: 315144977 }), send_elapsed: 0ns, ack_elapsed: 123.272861ms, rtt: 123.272861ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 441877, last_update: Instant { tv_sec: 574, tv_nsec: 438530429 }, next_time: Instant { tv_sec: 574, tv_nsec: 438530429 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T09:15:50.268049611Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d written 41 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:55493, at: Instant { tv_sec: 574, tv_nsec: 438530429 } })
[2025-09-12T09:15:50.268056925Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.268062145Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:50.268065752Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:50.268068607Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.378746389Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:55493 to [::]:443
[2025-09-12T09:15:50.378771416Z TRACE quiche_server] got packet Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false
[2025-09-12T09:15:50.378787086Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx pkt Short dcid=657db93ed4713fc07fe3d686d829d205fa5ca118 key_phase=false len=36 pn=21 
[2025-09-12T09:15:50.378806863Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-09-12T09:15:50.378813405Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d rx frm PADDING len=15
[2025-09-12T09:15:50.378829525Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d processed 57 bytes
[2025-09-12T09:15:50.378836839Z TRACE quiche_server] recv() would block
[2025-09-12T09:15:50.378840616Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:50.378843762Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.639145765Z TRACE quiche_server] timed out
[2025-09-12T09:15:50.639187794Z TRACE quiche] 1b2849e2b10914a669354001487ffa2f5dcfe56d draining timeout expired
[2025-09-12T09:15:50.639233730Z TRACE quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d done writing
[2025-09-12T09:15:50.639239241Z TRACE quiche_server] Collecting garbage
[2025-09-12T09:15:50.639252205Z INFO  quiche_server] 1b2849e2b10914a669354001487ffa2f5dcfe56d connection collected recv=15 sent=15 lost=0 retrans=0 sent_bytes=11708 recv_bytes=4704 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:55493 validation_state=Validated active=true recv=15 sent=15 lost=0 retrans=0 rtt=38.18935ms min_rtt=Some(34.712358ms) rttvar=12.059483ms cwnd=13500 sent_bytes=11708 recv_bytes=4704 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=182779]