[2025-04-30T18:25:44.234762041Z INFO  quiche_server] listening on [::]:443
[2025-04-30T18:25:44.234786326Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T18:25:44.234793910Z TRACE quiche_server] GSO detected: false
[2025-04-30T18:25:45.065243236Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:35273 to [::]:443
[2025-04-30T18:25:45.065279914Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:25:45.065287679Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:25:45.065389830Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:35273 to [::]:443
[2025-04-30T18:25:45.065395791Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:25:45.065399578Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:25:45.065420848Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:35273 to [::]:443
[2025-04-30T18:25:45.065426278Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:25:45.065431859Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:25:45.065459210Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.286577329Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.286611493Z TRACE quiche_server] got packet Initial version=1 dcid=f227a6529689ef309ffc144444a9cf29 scid=ea7b83c52c86621cb692305bbac3247c45f28398 token=
[2025-04-30T18:25:45.286621191Z DEBUG quiche_server] New connection: dcid=f227a6529689ef309ffc144444a9cf29 scid=876b3e729e6a6224f13ef4add5b20aeacaa6c895
[2025-04-30T18:25:45.286859566Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Initial version=1 dcid=f227a6529689ef309ffc144444a9cf29 scid=ea7b83c52c86621cb692305bbac3247c45f28398 token= len=287 pn=0 
[2025-04-30T18:25:45.286874855Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm CRYPTO off=0 len=266
[2025-04-30T18:25:45.286903549Z TRACE quiche::tls] checking peer ALPN Ok("h3") against Ok("h3")
[2025-04-30T18:25:45.287028252Z TRACE quiche::tls] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 write message lvl=Initial len=90
[2025-04-30T18:25:45.287061134Z TRACE quiche::tls] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 set write secret lvl=Handshake
[2025-04-30T18:25:45.287125093Z TRACE quiche::tls] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 write message lvl=Handshake len=696
[2025-04-30T18:25:45.287128109Z TRACE quiche::tls] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 set write secret lvl=OneRTT
[2025-04-30T18:25:45.287134401Z TRACE quiche::tls] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 set read secret lvl=Handshake
[2025-04-30T18:25:45.287164517Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 dropped invalid packet
[2025-04-30T18:25:45.287167643Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 1200 bytes
[2025-04-30T18:25:45.287185576Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.287197228Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Initial version=1 dcid=ea7b83c52c86621cb692305bbac3247c45f28398 scid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 len=99 pn=0 
[2025-04-30T18:25:45.287200394Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm ACK delay=44 blocks=[0..0] ecn_counts=None
[2025-04-30T18:25:45.287204883Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm CRYPTO off=0 len=90
[2025-04-30T18:25:45.287213719Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=998.97329ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=166 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 297, tv_nsec: 437211782 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 437211782 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 297, tv_nsec: 437211782 }, next_time: Instant { tv_sec: 297, tv_nsec: 437211782 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.287241541Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Handshake version=1 dcid=ea7b83c52c86621cb692305bbac3247c45f28398 scid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 len=700 pn=1 
[2025-04-30T18:25:45.287244406Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm CRYPTO off=0 len=696
[2025-04-30T18:25:45.287249816Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=998.937774ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=932 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 297, tv_nsec: 437211782 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 437211782 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 297, tv_nsec: 437211782 }, next_time: Instant { tv_sec: 297, tv_nsec: 437211782 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.287305781Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41545, at: Instant { tv_sec: 297, tv_nsec: 437211782 } })
[2025-04-30T18:25:45.287311512Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.287315980Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.287319597Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 done writing
[2025-04-30T18:25:45.287321902Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.320712702Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.320738771Z TRACE quiche_server] got packet Initial version=1 dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 scid=ea7b83c52c86621cb692305bbac3247c45f28398 token=
[2025-04-30T18:25:45.320753528Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Initial version=1 dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 scid=ea7b83c52c86621cb692305bbac3247c45f28398 token= len=23 pn=1 
[2025-04-30T18:25:45.320766523Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm ACK delay=87 blocks=[0..0] ecn_counts=None
[2025-04-30T18:25:45.320773937Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 packet newly acked 0
[2025-04-30T18:25:45.320801388Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Handshake version=1 dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 scid=ea7b83c52c86621cb692305bbac3247c45f28398 len=63 pn=2 
[2025-04-30T18:25:45.320808040Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm ACK delay=66 blocks=[1..1] ecn_counts=None
[2025-04-30T18:25:45.320811637Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 packet newly acked 1
[2025-04-30T18:25:45.320816125Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm CRYPTO off=0 len=36
[2025-04-30T18:25:45.320850550Z TRACE quiche::tls] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 set read secret lvl=OneRTT
[2025-04-30T18:25:45.320890314Z TRACE quiche::tls] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 write message lvl=OneRTT len=396
[2025-04-30T18:25:45.320900844Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 dropped epoch 1 state
[2025-04-30T18:25:45.320905322Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1350, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(ea7b83c52c86621cb692305bbac3247c45f28398), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T18:25:45.320928596Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 dropped epoch 0 state
[2025-04-30T18:25:45.320932513Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=1144 pn=3 
[2025-04-30T18:25:45.320940989Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ab, 27, ab, 9b, 00, 01, 59, 07, 2f, 80, ba, 4e, 39, 16, 42, f3, 33, f9, 16, 09] reset_token=[a9, 60, 73, aa, 70, 8a, 72, 06, cd, fe, 20, 6c, d9, a5, ec, 97]
[2025-04-30T18:25:45.320950417Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[de, 65, 69, de, 6e, 1c, 57, 52, e6, da, 43, 09, fb, 08, 78, 04, 33, 59, 45, ac] reset_token=[e3, 02, 1c, a0, 8d, e3, 81, e6, 4e, 09, 8f, 0b, b0, f7, 12, 39]
[2025-04-30T18:25:45.320957490Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[73, cd, 76, 58, 7f, 35, 20, d4, 9c, ca, cf, dd, e4, 64, 52, 0a, 8d, e6, f0, 96] reset_token=[77, b7, 01, 8f, 15, d8, 46, 1b, af, 68, 54, 61, e9, 5a, 0d, 49]
[2025-04-30T18:25:45.320964233Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[60, ef, e3, 8d, e2, 39, fd, 28, 75, 30, da, 5d, a0, b5, 4d, be, 18, 53, 09, fe] reset_token=[ec, 16, ca, 37, 81, 4a, 34, 59, 0d, 74, bf, 89, be, 56, a7, c0]
[2025-04-30T18:25:45.320971456Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[73, 13, db, bf, 3a, fb, f4, 12, bd, 8b, e9, e8, 4c, 22, 6a, 63, ed, c8, 89, 1e] reset_token=[a7, a7, 84, 0c, 60, fc, 4c, c1, b9, 24, 0c, ac, cc, 3d, 99, 82]
[2025-04-30T18:25:45.321013515Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[a1, 5d, f4, eb, b4, fa, 29, ba, e9, 1a, b8, 96, 00, 02, b1, cf, a2, ff, 07, 46] reset_token=[dd, 4e, 74, 63, 8c, 2d, 23, 62, d1, bf, ae, a6, c3, c3, 39, ea]
[2025-04-30T18:25:45.321025247Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[8b, dd, c6, ba, 6a, 58, e0, c8, 0e, e7, 5c, 8a, d2, 06, 97, 57, 1d, 17, 0a, fb] reset_token=[a3, c7, bc, df, 8b, ca, 82, 94, 01, e6, 89, ce, f0, 62, 1f, bd]
[2025-04-30T18:25:45.321031829Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T18:25:45.321043851Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm PADDING len=823
[2025-04-30T18:25:45.321061825Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 1350 bytes
[2025-04-30T18:25:45.321082303Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm SETTINGS stream=3 len=18
[2025-04-30T18:25:45.321113922Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 open GREASE stream 15
[2025-04-30T18:25:45.321123701Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 stream id 2 is readable
[2025-04-30T18:25:45.321133198Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 2
[2025-04-30T18:25:45.321139871Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 open peer's control stream 2
[2025-04-30T18:25:45.321146574Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 2
[2025-04-30T18:25:45.321152194Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 2
[2025-04-30T18:25:45.321158105Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 16 bytes on stream 2
[2025-04-30T18:25:45.321162994Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(2872241909887779400, 2543301606432504325)]), additional_settings=Some([(2872241909887779400, 2543301606432504325)]) stream=2 payload_len=16
[2025-04-30T18:25:45.321180006Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.321186268Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.321195956Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=23 pn=4 
[2025-04-30T18:25:45.321204382Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T18:25:45.321262621Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 44 bytes
[2025-04-30T18:25:45.321268542Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 stream id 6 is readable
[2025-04-30T18:25:45.321276707Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 6
[2025-04-30T18:25:45.321287998Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.321293518Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.321301533Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=23 pn=5 
[2025-04-30T18:25:45.321309649Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T18:25:45.321321190Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 44 bytes
[2025-04-30T18:25:45.321326260Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 stream id 10 is readable
[2025-04-30T18:25:45.321332702Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 10
[2025-04-30T18:25:45.321342981Z TRACE quiche_server] got 111 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.321348601Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.321355474Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=90 pn=6 
[2025-04-30T18:25:45.321362718Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T18:25:45.321373679Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 111 bytes
[2025-04-30T18:25:45.321378227Z DEBUG quiche_apps::common] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 response stream 0 is writable with capacity Ok(13500)
[2025-04-30T18:25:45.321383577Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 stream id 0 is readable
[2025-04-30T18:25:45.321389578Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 0
[2025-04-30T18:25:45.321394878Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 7 bytes on stream 0
[2025-04-30T18:25:45.321408934Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 0
[2025-04-30T18:25:45.321414355Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm UNKNOWN raw_type=2399076501356263634 stream=0 payload_len=0
[2025-04-30T18:25:45.321424854Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 0
[2025-04-30T18:25:45.321430795Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 7 bytes on stream 0
[2025-04-30T18:25:45.321436556Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 0
[2025-04-30T18:25:45.321442848Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 18 bytes on stream 0
[2025-04-30T18:25:45.321447356Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm UNKNOWN raw_type=2015011898135882212 stream=0 payload_len=18
[2025-04-30T18:25:45.321455191Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 0
[2025-04-30T18:25:45.321460962Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 0
[2025-04-30T18:25:45.321467023Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 30 bytes on stream 0
[2025-04-30T18:25:45.321471401Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm HEADERS stream=0 payload_len=30
[2025-04-30T18:25:45.321476751Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T18:25:45.321481180Z TRACE quiche::h3::qpack::decoder] Indexed index=17 static=true
[2025-04-30T18:25:45.321485628Z TRACE quiche::h3::qpack::decoder] Indexed index=23 static=true
[2025-04-30T18:25:45.321491429Z TRACE quiche::h3::qpack::decoder] Literal name_idx=0 static=true value=[115, 101, 114, 118, 101, 114, 52]
[2025-04-30T18:25:45.321498943Z TRACE quiche::h3::qpack::decoder] Literal name_idx=1 static=true value=[47, 106, 107, 116, 100, 99, 111, 108, 103, 113, 101]
[2025-04-30T18:25:45.321505094Z TRACE quiche::h3::qpack::decoder] Literal name_idx=95 static=true value=[113, 117, 105, 99, 104, 101]
[2025-04-30T18:25:45.321519331Z INFO  quiche_apps::common] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 got request [(":method", "GET"), (":scheme", "https"), (":authority", "server4"), (":path", "/jktdcolgqe"), ("user-agent", "quiche")] on stream id 0
[2025-04-30T18:25:45.321579854Z INFO  quiche_apps::common] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 prioritizing response on stream 0 as Priority { urgency: 3, incremental: false }
[2025-04-30T18:25:45.321595623Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm GREASE stream=0 len=0
[2025-04-30T18:25:45.321608147Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm GREASE stream=0 len=18
[2025-04-30T18:25:45.321620911Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm HEADERS stream=0 len=16 fin=false
[2025-04-30T18:25:45.321633354Z DEBUG quiche_apps::common] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 response stream 0 is writable with capacity Ok(13446)
[2025-04-30T18:25:45.321645276Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm DATA stream=0 len=5120 fin=true
[2025-04-30T18:25:45.321657329Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.321663961Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.321672688Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=48 pn=7 
[2025-04-30T18:25:45.321682075Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T18:25:45.321693186Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 69 bytes
[2025-04-30T18:25:45.321698075Z TRACE quiche::h3] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 stream id 14 is readable
[2025-04-30T18:25:45.321705038Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 1 bytes on stream 14
[2025-04-30T18:25:45.321717181Z TRACE quiche::h3::stream] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 read 7 bytes on stream 14
[2025-04-30T18:25:45.321727190Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.321740424Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.321757105Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Short dcid=ea7b83c52c86621cb692305bbac3247c45f28398 key_phase=false len=710 pn=2 
[2025-04-30T18:25:45.321763137Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm ACK delay=8 blocks=[3..7] ecn_counts=None
[2025-04-30T18:25:45.321769950Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[60, fc, f7, 3a, 4f, 4f, 58, f2, 6e, a9, eb, 5c, 8b, cc, 9b, 98, a6, 3b, a9, 2e] reset_token=[50, f3, 4c, 47, 9a, c0, 0d, c0, e3, 91, b2, 65, b8, 43, 44, 65]
[2025-04-30T18:25:45.321782042Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[ee, 51, 75, 4e, a1, 91, e4, 49, b0, ae, 0a, 6c, c1, 43, 2c, 6f, 35, 79, 28, 22] reset_token=[fa, ab, b8, c4, f6, 9b, a9, c9, 8a, e8, f7, 9f, 4c, 0e, 07, a1]
[2025-04-30T18:25:45.321788905Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[96, 4e, 2a, ed, 18, b9, 8d, bd, 3b, 99, 60, 5e, c4, 95, 0e, 8d, ab, 76, d3, de] reset_token=[1b, 43, 54, 71, e5, 80, 75, b7, 9e, 85, d8, 0b, 2c, 04, 0b, 01]
[2025-04-30T18:25:45.321795437Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[a5, 3d, 43, ed, 58, 01, 70, 25, 10, 74, cc, 5d, 02, 99, c0, 7b, 77, b5, 76, c8] reset_token=[6f, 47, 5c, 6d, ad, 10, 6b, 37, 42, 1c, 6b, d2, a3, ba, f4, d9]
[2025-04-30T18:25:45.321801749Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[e6, a5, 34, b0, bb, 7e, 1e, a0, 39, 79, 04, 6e, 5c, ce, f0, 5f, 33, 78, b8, bb] reset_token=[3c, fa, f9, 2e, 3a, 2f, 00, 5b, 80, 10, d6, 18, 70, 62, ff, ef]
[2025-04-30T18:25:45.321808121Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[f3, 50, 33, 6c, 0b, 3d, 4c, 0a, c4, ee, 58, 93, c3, ef, 6f, 3f, 0e, 37, aa, ce] reset_token=[38, 49, 02, 4c, fb, 78, bf, 64, df, f9, 24, 6b, d2, 12, 23, bf]
[2025-04-30T18:25:45.321814703Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[1d, 4c, df, cc, bb, 07, 97, c7, 6c, 51, ac, cb, 66, 12, 82, 71, b4, 19, 8f, 20] reset_token=[8e, 3f, 05, ab, b9, b4, f5, 91, 7a, 4e, 99, 16, 29, d3, 15, 1a]
[2025-04-30T18:25:45.321821025Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm HANDSHAKE_DONE
[2025-04-30T18:25:45.321823951Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm CRYPTO off=0 len=396
[2025-04-30T18:25:45.321826916Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm STREAM id=3 off=0 len=19 fin=false
[2025-04-30T18:25:45.321843557Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=108.848471ms latest_rtt=33.612826ms srtt=33.567634ms min_rtt=33.561179ms rttvar=12.598352ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=748 app_limited=true congestion_recovery_start_time=None Rate { delivered: 932, delivered_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27770, is_app_limited: true, interval: 33.561179ms, delivered: 932, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 437211782 }), send_elapsed: 0ns, ack_elapsed: 33.561179ms, rtt: 33.561179ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502716, last_update: Instant { tv_sec: 297, tv_nsec: 471755529 }, next_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.321913308Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 written 748 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41545, at: Instant { tv_sec: 297, tv_nsec: 471755529 } })
[2025-04-30T18:25:45.321920341Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.321926542Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.321933786Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12752 out_len=1350 left=1312
[2025-04-30T18:25:45.321939106Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Short dcid=ea7b83c52c86621cb692305bbac3247c45f28398 key_phase=false len=6 pn=3 
[2025-04-30T18:25:45.321942172Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T18:25:45.321949305Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=108.940554ms latest_rtt=33.612826ms srtt=33.567634ms min_rtt=33.561179ms rttvar=12.598352ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=792 app_limited=true congestion_recovery_start_time=None Rate { delivered: 932, delivered_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27770, is_app_limited: true, interval: 33.561179ms, delivered: 932, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 437211782 }), send_elapsed: 0ns, ack_elapsed: 33.561179ms, rtt: 33.561179ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502716, last_update: Instant { tv_sec: 297, tv_nsec: 471755529 }, next_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.322011782Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 written 44 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41545, at: Instant { tv_sec: 297, tv_nsec: 471755529 } })
[2025-04-30T18:25:45.322031349Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.322036639Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.322043822Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12708 out_len=1350 left=1312
[2025-04-30T18:25:45.322048470Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Short dcid=ea7b83c52c86621cb692305bbac3247c45f28398 key_phase=false len=6 pn=4 
[2025-04-30T18:25:45.322051086Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T18:25:45.322057828Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=108.941796ms latest_rtt=33.612826ms srtt=33.567634ms min_rtt=33.561179ms rttvar=12.598352ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=836 app_limited=true congestion_recovery_start_time=None Rate { delivered: 932, delivered_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27770, is_app_limited: true, interval: 33.561179ms, delivered: 932, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 437211782 }), send_elapsed: 0ns, ack_elapsed: 33.561179ms, rtt: 33.561179ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502716, last_update: Instant { tv_sec: 297, tv_nsec: 471755529 }, next_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.322105277Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 written 44 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41545, at: Instant { tv_sec: 297, tv_nsec: 471755529 } })
[2025-04-30T18:25:45.322109805Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.322113292Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.322117650Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12664 out_len=1350 left=1312
[2025-04-30T18:25:45.322121257Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Short dcid=ea7b83c52c86621cb692305bbac3247c45f28398 key_phase=false len=1312 pn=5 
[2025-04-30T18:25:45.322123731Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm STREAM id=0 off=0 len=1307 fin=false
[2025-04-30T18:25:45.322129713Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=108.946315ms latest_rtt=33.612826ms srtt=33.567634ms min_rtt=33.561179ms rttvar=12.598352ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2186 app_limited=true congestion_recovery_start_time=None Rate { delivered: 932, delivered_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27770, is_app_limited: true, interval: 33.561179ms, delivered: 932, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 437211782 }), send_elapsed: 0ns, ack_elapsed: 33.561179ms, rtt: 33.561179ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502716, last_update: Instant { tv_sec: 297, tv_nsec: 471755529 }, next_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.322140493Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11314 out_len=1350 left=1312
[2025-04-30T18:25:45.322143468Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Short dcid=ea7b83c52c86621cb692305bbac3247c45f28398 key_phase=false len=1312 pn=6 
[2025-04-30T18:25:45.322145803Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm STREAM id=0 off=1307 len=1306 fin=false
[2025-04-30T18:25:45.322150812Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=108.94916ms latest_rtt=33.612826ms srtt=33.567634ms min_rtt=33.561179ms rttvar=12.598352ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3536 app_limited=true congestion_recovery_start_time=None Rate { delivered: 932, delivered_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27770, is_app_limited: true, interval: 33.561179ms, delivered: 932, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 437211782 }), send_elapsed: 0ns, ack_elapsed: 33.561179ms, rtt: 33.561179ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502716, last_update: Instant { tv_sec: 297, tv_nsec: 471755529 }, next_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.322164578Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9964 out_len=1350 left=1312
[2025-04-30T18:25:45.322167604Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Short dcid=ea7b83c52c86621cb692305bbac3247c45f28398 key_phase=false len=1312 pn=7 
[2025-04-30T18:25:45.322169978Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm STREAM id=0 off=2613 len=1306 fin=false
[2025-04-30T18:25:45.322174436Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=108.94952ms latest_rtt=33.612826ms srtt=33.567634ms min_rtt=33.561179ms rttvar=12.598352ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4886 app_limited=true congestion_recovery_start_time=None Rate { delivered: 932, delivered_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27770, is_app_limited: true, interval: 33.561179ms, delivered: 932, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 437211782 }), send_elapsed: 0ns, ack_elapsed: 33.561179ms, rtt: 33.561179ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502716, last_update: Instant { tv_sec: 297, tv_nsec: 471755529 }, next_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.322184114Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8614 out_len=1350 left=1312
[2025-04-30T18:25:45.322187050Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Short dcid=ea7b83c52c86621cb692305bbac3247c45f28398 key_phase=false len=1264 pn=8 
[2025-04-30T18:25:45.322189394Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm STREAM id=0 off=3919 len=1258 fin=true
[2025-04-30T18:25:45.322193772Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=108.949921ms latest_rtt=33.612826ms srtt=33.567634ms min_rtt=33.561179ms rttvar=12.598352ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6188 app_limited=true congestion_recovery_start_time=None Rate { delivered: 932, delivered_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27770, is_app_limited: true, interval: 33.561179ms, delivered: 932, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 437211782 }), send_elapsed: 0ns, ack_elapsed: 33.561179ms, rtt: 33.561179ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502716, last_update: Instant { tv_sec: 297, tv_nsec: 471755529 }, next_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.322256700Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 written 5352 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41545, at: Instant { tv_sec: 297, tv_nsec: 471755529 } })
[2025-04-30T18:25:45.322261148Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.322264555Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.322268682Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7312 out_len=1350 left=1312
[2025-04-30T18:25:45.322272129Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx pkt Short dcid=ea7b83c52c86621cb692305bbac3247c45f28398 key_phase=false len=31 pn=9 
[2025-04-30T18:25:45.322274564Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 tx frm STREAM id=15 off=0 len=26 fin=true
[2025-04-30T18:25:45.322279943Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 timer=108.947387ms latest_rtt=33.612826ms srtt=33.567634ms min_rtt=33.561179ms rttvar=12.598352ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6257 app_limited=true congestion_recovery_start_time=None Rate { delivered: 932, delivered_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27770, is_app_limited: true, interval: 33.561179ms, delivered: 932, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 437211782 }), send_elapsed: 0ns, ack_elapsed: 33.561179ms, rtt: 33.561179ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 502716, last_update: Instant { tv_sec: 297, tv_nsec: 471755529 }, next_time: Instant { tv_sec: 297, tv_nsec: 471755529 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.322304690Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41545, at: Instant { tv_sec: 297, tv_nsec: 471755529 } })
[2025-04-30T18:25:45.322308387Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.322311673Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.322314107Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 done writing
[2025-04-30T18:25:45.322316311Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.353443026Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.353464937Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.353478462Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=22 pn=8 
[2025-04-30T18:25:45.353490645Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm ACK delay=11 blocks=[2..4] ecn_counts=None
[2025-04-30T18:25:45.353498059Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 packet newly acked 2
[2025-04-30T18:25:45.353503840Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 packet newly acked 3
[2025-04-30T18:25:45.353507837Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 packet newly acked 4
[2025-04-30T18:25:45.353536060Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 43 bytes
[2025-04-30T18:25:45.353557420Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.353562430Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 done writing
[2025-04-30T18:25:45.353565726Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.354293056Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.354299859Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.354304397Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=22 pn=9 
[2025-04-30T18:25:45.354308856Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm ACK delay=22 blocks=[2..5] ecn_counts=None
[2025-04-30T18:25:45.354312022Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 packet newly acked 5
[2025-04-30T18:25:45.354321579Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 43 bytes
[2025-04-30T18:25:45.354325467Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.354328011Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 done writing
[2025-04-30T18:25:45.354330146Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.355253352Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.355257069Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.355261167Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=22 pn=10 
[2025-04-30T18:25:45.355265245Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm ACK delay=5 blocks=[2..6] ecn_counts=None
[2025-04-30T18:25:45.355268070Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 packet newly acked 6
[2025-04-30T18:25:45.355276085Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 43 bytes
[2025-04-30T18:25:45.355279792Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.355282296Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 done writing
[2025-04-30T18:25:45.355284460Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.356329305Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.356335026Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.356339023Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=22 pn=11 
[2025-04-30T18:25:45.356343091Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm ACK delay=3 blocks=[2..7] ecn_counts=None
[2025-04-30T18:25:45.356345946Z TRACE quiche::recovery::congestion::recovery] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 packet newly acked 7
[2025-04-30T18:25:45.356352999Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 43 bytes
[2025-04-30T18:25:45.356356806Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.356359261Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 done writing
[2025-04-30T18:25:45.356361395Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.357424904Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:41545 to [::]:443
[2025-04-30T18:25:45.357430244Z TRACE quiche_server] got packet Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false
[2025-04-30T18:25:45.357434382Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx pkt Short dcid=876b3e729e6a6224f13ef4add5b20aeacaa6c895 key_phase=false len=28 pn=12 
[2025-04-30T18:25:45.357439271Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 rx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T18:25:45.357446074Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 processed 49 bytes
[2025-04-30T18:25:45.357455011Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.357457355Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 done writing
[2025-04-30T18:25:45.357459479Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.514664761Z TRACE quiche_server] timed out
[2025-04-30T18:25:45.514693685Z TRACE quiche] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 draining timeout expired
[2025-04-30T18:25:45.514738699Z TRACE quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 done writing
[2025-04-30T18:25:45.514741955Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.514745201Z INFO  quiche_server] 876b3e729e6a6224f13ef4add5b20aeacaa6c895 connection collected recv=13 sent=10 lost=0 retrans=0 sent_bytes=7189 recv_bytes=2172 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:41545 validation_state=Validated active=true recv=13 sent=10 lost=0 retrans=0 rtt=33.421076ms min_rtt=Some(31.743579ms) rttvar=4.693312ms cwnd=13500 sent_bytes=7189 recv_bytes=2172 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=153920]
[2025-04-30T18:25:45.676509930Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.676540306Z TRACE quiche_server] got packet Initial version=1 dcid=9d9eec48ccfe7487a1c712f8f09c4a54 scid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 token=
[2025-04-30T18:25:45.676549714Z DEBUG quiche_server] New connection: dcid=9d9eec48ccfe7487a1c712f8f09c4a54 scid=747b303c77f5396506b592183e6e5fc5dcf0156e
[2025-04-30T18:25:45.676716366Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Initial version=1 dcid=9d9eec48ccfe7487a1c712f8f09c4a54 scid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 token= len=510 pn=0 
[2025-04-30T18:25:45.676730141Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm CRYPTO off=0 len=489
[2025-04-30T18:25:45.676757442Z TRACE quiche::tls] checking peer ALPN Ok("h3") against Ok("h3")
[2025-04-30T18:25:45.676874632Z TRACE quiche::tls] 747b303c77f5396506b592183e6e5fc5dcf0156e write message lvl=Initial len=96
[2025-04-30T18:25:45.676894769Z TRACE quiche::tls] 747b303c77f5396506b592183e6e5fc5dcf0156e set write secret lvl=Handshake
[2025-04-30T18:25:45.676923824Z TRACE quiche::tls] 747b303c77f5396506b592183e6e5fc5dcf0156e write message lvl=Handshake len=148
[2025-04-30T18:25:45.676926990Z TRACE quiche::tls] 747b303c77f5396506b592183e6e5fc5dcf0156e set write secret lvl=OneRTT
[2025-04-30T18:25:45.676934373Z TRACE quiche::tls] 747b303c77f5396506b592183e6e5fc5dcf0156e set read secret lvl=Handshake
[2025-04-30T18:25:45.676963408Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e dropped invalid packet
[2025-04-30T18:25:45.676967425Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 1200 bytes
[2025-04-30T18:25:45.677016267Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.677035122Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Initial version=1 dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 scid=747b303c77f5396506b592183e6e5fc5dcf0156e len=105 pn=0 
[2025-04-30T18:25:45.677039129Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm ACK delay=41 blocks=[0..0] ecn_counts=None
[2025-04-30T18:25:45.677044490Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm CRYPTO off=0 len=96
[2025-04-30T18:25:45.677055780Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=998.966797ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=172 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 297, tv_nsec: 827047371 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 827047371 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 297, tv_nsec: 827047371 }, next_time: Instant { tv_sec: 297, tv_nsec: 827047371 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.677090616Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Handshake version=1 dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 scid=747b303c77f5396506b592183e6e5fc5dcf0156e len=152 pn=1 
[2025-04-30T18:25:45.677094112Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm CRYPTO off=0 len=148
[2025-04-30T18:25:45.677100735Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=998.922385ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=390 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 297, tv_nsec: 827047371 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 827047371 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 297, tv_nsec: 827047371 }, next_time: Instant { tv_sec: 297, tv_nsec: 827047371 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.677162881Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:56474, at: Instant { tv_sec: 297, tv_nsec: 827047371 } })
[2025-04-30T18:25:45.677170114Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.677175775Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.677180845Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.677183740Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.710404482Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.710435671Z TRACE quiche_server] got packet Initial version=1 dcid=747b303c77f5396506b592183e6e5fc5dcf0156e scid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 token=
[2025-04-30T18:25:45.710452663Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Initial version=1 dcid=747b303c77f5396506b592183e6e5fc5dcf0156e scid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 token= len=22 pn=1 
[2025-04-30T18:25:45.710466529Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=57 blocks=[0..0] ecn_counts=None
[2025-04-30T18:25:45.710473943Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 0
[2025-04-30T18:25:45.710501284Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Handshake version=1 dcid=747b303c77f5396506b592183e6e5fc5dcf0156e scid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 len=62 pn=2 
[2025-04-30T18:25:45.710508187Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=39 blocks=[1..1] ecn_counts=None
[2025-04-30T18:25:45.710511613Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 1
[2025-04-30T18:25:45.710515851Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm CRYPTO off=0 len=36
[2025-04-30T18:25:45.710537732Z TRACE quiche::tls] 747b303c77f5396506b592183e6e5fc5dcf0156e set read secret lvl=OneRTT
[2025-04-30T18:25:45.710590841Z TRACE quiche::tls] 747b303c77f5396506b592183e6e5fc5dcf0156e write message lvl=OneRTT len=396
[2025-04-30T18:25:45.710602353Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e dropped epoch 1 state
[2025-04-30T18:25:45.710606891Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=None resumed=true TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1350, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(dfb2629e0b68476ad0678502d3cb8bf36f40d227), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T18:25:45.710630966Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e dropped epoch 0 state
[2025-04-30T18:25:45.710634813Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=1146 pn=3 
[2025-04-30T18:25:45.710643129Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[75, 18, 6d, 42, 3b, 83, e2, 2a, b6, 32, 45, da, c7, 47, 4c, c7, fa, 50, 3e, c9] reset_token=[5e, 5c, 07, 58, a6, 0f, 79, fa, 58, 75, 66, 29, 38, 3f, d0, 4b]
[2025-04-30T18:25:45.710653128Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[d9, d1, f6, 51, 8a, 78, 67, df, 8d, 93, d0, 46, bd, 9d, 16, 46, 76, 2f, 38, 92] reset_token=[0f, c6, b0, c0, 40, 1f, 6d, f9, d0, 19, d6, 79, d3, 03, 98, 1c]
[2025-04-30T18:25:45.710659860Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[02, e1, aa, b0, ae, 5d, 30, bf, 27, ea, f7, b7, 4e, c1, f0, df, 10, 07, a0, e6] reset_token=[47, 83, 0b, 49, 63, f2, 0d, dd, 46, e7, 1f, 23, 9f, 40, ae, ab]
[2025-04-30T18:25:45.710666493Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[37, d7, 7f, c2, 39, d5, 3d, 87, a2, 3d, 02, e3, ee, fe, 2b, 9a, b1, 48, f8, 7b] reset_token=[45, 47, f1, 38, 0a, cd, 2d, 3f, 7d, 35, f1, 40, c0, dc, 7c, 9d]
[2025-04-30T18:25:45.710673436Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[94, f3, db, 10, bd, 3d, f3, 99, e5, ac, 1b, 14, bf, 4f, 5c, 8e, c3, 7b, 76, 7c] reset_token=[c6, 89, a6, e9, dc, 4a, 5f, f4, 09, 55, 97, 52, ca, 41, 24, 1d]
[2025-04-30T18:25:45.710679828Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[1f, cc, e2, 88, 22, a1, 73, 81, 11, 71, e7, ec, 67, 53, 78, f4, cd, 8f, 28, 2a] reset_token=[cf, f6, f6, 23, 62, ae, dc, b0, 5a, b9, b3, 59, aa, de, 38, b3]
[2025-04-30T18:25:45.710686129Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[90, bd, 91, bd, f6, cc, 55, 1c, 40, 63, 15, 2b, c8, c0, c1, 13, 7a, 90, ff, 0e] reset_token=[1f, f4, 3f, a2, 18, 0e, 5f, 39, d2, 94, d7, 67, f3, 89, 3d, a9]
[2025-04-30T18:25:45.710691379Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T18:25:45.710699955Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm PADDING len=825
[2025-04-30T18:25:45.710710856Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 1350 bytes
[2025-04-30T18:25:45.710725674Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm SETTINGS stream=3 len=18
[2025-04-30T18:25:45.710741994Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e open GREASE stream 15
[2025-04-30T18:25:45.710753415Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e stream id 2 is readable
[2025-04-30T18:25:45.710759637Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 2
[2025-04-30T18:25:45.710763414Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e open peer's control stream 2
[2025-04-30T18:25:45.710766720Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 2
[2025-04-30T18:25:45.710769766Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 2
[2025-04-30T18:25:45.710773092Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 16 bytes on stream 2
[2025-04-30T18:25:45.710776048Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(1589245688909653556, 1921075344492085285)]), additional_settings=Some([(1589245688909653556, 1921075344492085285)]) stream=2 payload_len=16
[2025-04-30T18:25:45.710787068Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.710790355Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.710794753Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=23 pn=4 
[2025-04-30T18:25:45.710798960Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T18:25:45.710828035Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 44 bytes
[2025-04-30T18:25:45.710831091Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e stream id 6 is readable
[2025-04-30T18:25:45.710834838Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 6
[2025-04-30T18:25:45.710842522Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.710845608Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.710849605Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=23 pn=5 
[2025-04-30T18:25:45.710853913Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T18:25:45.710859413Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 44 bytes
[2025-04-30T18:25:45.710861998Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e stream id 10 is readable
[2025-04-30T18:25:45.710865305Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 10
[2025-04-30T18:25:45.710870514Z TRACE quiche_server] got 111 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.710873199Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.710876716Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=90 pn=6 
[2025-04-30T18:25:45.710880583Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T18:25:45.710886204Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 111 bytes
[2025-04-30T18:25:45.710888829Z DEBUG quiche_apps::common] 747b303c77f5396506b592183e6e5fc5dcf0156e response stream 0 is writable with capacity Ok(13500)
[2025-04-30T18:25:45.710892586Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e stream id 0 is readable
[2025-04-30T18:25:45.710896213Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 0
[2025-04-30T18:25:45.710899338Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 7 bytes on stream 0
[2025-04-30T18:25:45.710902404Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 0
[2025-04-30T18:25:45.710908125Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm UNKNOWN raw_type=611184251433127538 stream=0 payload_len=0
[2025-04-30T18:25:45.710912924Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 0
[2025-04-30T18:25:45.710915949Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 7 bytes on stream 0
[2025-04-30T18:25:45.710918845Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 0
[2025-04-30T18:25:45.710922091Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 18 bytes on stream 0
[2025-04-30T18:25:45.710924495Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm UNKNOWN raw_type=2147874996086930850 stream=0 payload_len=18
[2025-04-30T18:25:45.710928383Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 0
[2025-04-30T18:25:45.710931398Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 0
[2025-04-30T18:25:45.710934504Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 30 bytes on stream 0
[2025-04-30T18:25:45.710936869Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm HEADERS stream=0 payload_len=30
[2025-04-30T18:25:45.710939814Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T18:25:45.710942319Z TRACE quiche::h3::qpack::decoder] Indexed index=17 static=true
[2025-04-30T18:25:45.710945074Z TRACE quiche::h3::qpack::decoder] Indexed index=23 static=true
[2025-04-30T18:25:45.710948731Z TRACE quiche::h3::qpack::decoder] Literal name_idx=0 static=true value=[115, 101, 114, 118, 101, 114, 52]
[2025-04-30T18:25:45.710953149Z TRACE quiche::h3::qpack::decoder] Literal name_idx=1 static=true value=[47, 100, 113, 122, 103, 115, 111, 106, 121, 111, 97]
[2025-04-30T18:25:45.710956455Z TRACE quiche::h3::qpack::decoder] Literal name_idx=95 static=true value=[113, 117, 105, 99, 104, 101]
[2025-04-30T18:25:45.710964210Z INFO  quiche_apps::common] 747b303c77f5396506b592183e6e5fc5dcf0156e got request [(":method", "GET"), (":scheme", "https"), (":authority", "server4"), (":path", "/dqzgsojyoa"), ("user-agent", "quiche")] on stream id 0
[2025-04-30T18:25:45.711047796Z INFO  quiche_apps::common] 747b303c77f5396506b592183e6e5fc5dcf0156e prioritizing response on stream 0 as Priority { urgency: 3, incremental: false }
[2025-04-30T18:25:45.711060249Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm GREASE stream=0 len=0
[2025-04-30T18:25:45.711066521Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm GREASE stream=0 len=18
[2025-04-30T18:25:45.711073594Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm HEADERS stream=0 len=17 fin=false
[2025-04-30T18:25:45.711081319Z DEBUG quiche_apps::common] 747b303c77f5396506b592183e6e5fc5dcf0156e response stream 0 is writable with capacity Ok(13445)
[2025-04-30T18:25:45.711088402Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm DATA stream=0 len=10240 fin=true
[2025-04-30T18:25:45.711096046Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.711099373Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.711104141Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=48 pn=7 
[2025-04-30T18:25:45.711109431Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T18:25:45.711116404Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 69 bytes
[2025-04-30T18:25:45.711119210Z TRACE quiche::h3] 747b303c77f5396506b592183e6e5fc5dcf0156e stream id 14 is readable
[2025-04-30T18:25:45.711122836Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 1 bytes on stream 14
[2025-04-30T18:25:45.711130941Z TRACE quiche::h3::stream] 747b303c77f5396506b592183e6e5fc5dcf0156e read 7 bytes on stream 14
[2025-04-30T18:25:45.711136422Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.711144707Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.711154385Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=710 pn=2 
[2025-04-30T18:25:45.711157241Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm ACK delay=4 blocks=[3..7] ecn_counts=None
[2025-04-30T18:25:45.711160647Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[d2, 38, b5, 44, 94, cd, 42, e7, e0, c6, 0f, ec, 14, 73, f1, 65, 72, 5a, c3, 42] reset_token=[99, 4a, 22, 5d, a2, 52, ec, bf, 0b, 07, d6, 29, 08, 4a, f7, e2]
[2025-04-30T18:25:45.711167029Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[11, ec, 54, 1b, db, 61, ae, e8, f8, 05, 81, 5e, ae, 65, c9, 36, d6, 98, e6, 40] reset_token=[ad, b9, 7c, e1, 17, 58, 41, c5, a2, 21, 2b, 19, 4c, ad, 88, f7]
[2025-04-30T18:25:45.711172519Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[1c, c9, f4, b6, bf, 96, 36, 35, 3b, cc, 88, 99, 9a, f1, 76, df, 91, d8, b5, 27] reset_token=[fe, f8, 4e, 55, 62, 30, 1b, 76, b2, 77, b7, cb, ed, 35, 7f, 97]
[2025-04-30T18:25:45.711177949Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[a3, 7e, f5, 22, 82, 34, 3f, 64, 27, 78, 17, ec, cf, 81, cb, 6d, 63, 50, 69, 28] reset_token=[a6, 7f, 3c, 8a, e4, 94, 3d, 0c, f4, dc, b4, e8, cd, 97, 4b, 2e]
[2025-04-30T18:25:45.711183189Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[24, 68, 6a, cd, 97, 74, 3b, fd, f1, f2, 95, aa, 27, 12, fc, c3, bd, 20, 21, 4c] reset_token=[17, d2, d1, 07, 03, 33, 1d, 3e, 60, a0, de, 2f, d2, e9, 95, ae]
[2025-04-30T18:25:45.711188509Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[41, e8, 2a, 8a, fb, ae, ff, 8e, 6b, a3, 9b, 60, 5e, 3c, d5, 02, 2a, 0f, b2, 72] reset_token=[b3, 75, ed, 4c, fe, 88, e3, b8, 40, 6b, 47, e5, 94, 31, 3b, 03]
[2025-04-30T18:25:45.711193869Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[0e, b6, 37, 2a, 52, 4e, df, d2, b5, 27, 4b, 96, 70, 58, 37, bf, c4, fe, b4, b0] reset_token=[80, c2, 1b, 87, 0f, f4, df, f1, 4c, 37, a3, f6, 93, 60, e0, d6]
[2025-04-30T18:25:45.711199209Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm HANDSHAKE_DONE
[2025-04-30T18:25:45.711201464Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm CRYPTO off=0 len=396
[2025-04-30T18:25:45.711203858Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=3 off=0 len=19 fin=false
[2025-04-30T18:25:45.711216351Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.541577ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=748 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711282765Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e written 748 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:56474, at: Instant { tv_sec: 297, tv_nsec: 861162798 } })
[2025-04-30T18:25:45.711289839Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.711295589Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.711301370Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12752 out_len=1350 left=1312
[2025-04-30T18:25:45.711306450Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=6 pn=3 
[2025-04-30T18:25:45.711309065Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T18:25:45.711316008Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.601089ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=792 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711353047Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e written 44 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:56474, at: Instant { tv_sec: 297, tv_nsec: 861162798 } })
[2025-04-30T18:25:45.711357325Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.711360982Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.711366192Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12708 out_len=1350 left=1312
[2025-04-30T18:25:45.711369628Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=6 pn=4 
[2025-04-30T18:25:45.711372113Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T18:25:45.711377152Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.605236ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=836 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711422257Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e written 44 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:56474, at: Instant { tv_sec: 297, tv_nsec: 861162798 } })
[2025-04-30T18:25:45.711426825Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.711430281Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.711435221Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12664 out_len=1350 left=1312
[2025-04-30T18:25:45.711438917Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1312 pn=5 
[2025-04-30T18:25:45.711441402Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=0 off=0 len=1307 fin=false
[2025-04-30T18:25:45.711447744Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.603783ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2186 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711458925Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11314 out_len=1350 left=1312
[2025-04-30T18:25:45.711461900Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1312 pn=6 
[2025-04-30T18:25:45.711464235Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=0 off=1307 len=1306 fin=false
[2025-04-30T18:25:45.711469074Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.60775ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3536 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711483421Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9964 out_len=1350 left=1312
[2025-04-30T18:25:45.711486426Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1312 pn=7 
[2025-04-30T18:25:45.711488781Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=0 off=2613 len=1306 fin=false
[2025-04-30T18:25:45.711493169Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.608061ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4886 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 5, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711503328Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8614 out_len=1350 left=1312
[2025-04-30T18:25:45.711506214Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1312 pn=8 
[2025-04-30T18:25:45.711508558Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=0 off=3919 len=1306 fin=false
[2025-04-30T18:25:45.711512716Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.608653ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6236 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 5, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711525770Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7264 out_len=1350 left=1312
[2025-04-30T18:25:45.711529647Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1312 pn=9 
[2025-04-30T18:25:45.711532002Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=0 off=5225 len=1306 fin=false
[2025-04-30T18:25:45.711536150Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.607631ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7586 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 5, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711546208Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=5914 out_len=1350 left=1312
[2025-04-30T18:25:45.711549034Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1312 pn=10 
[2025-04-30T18:25:45.711551398Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=0 off=6531 len=1306 fin=false
[2025-04-30T18:25:45.711555836Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.608472ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8936 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 5, last_sent_packet: 10, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711565945Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4564 out_len=1350 left=1312
[2025-04-30T18:25:45.711568720Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1312 pn=11 
[2025-04-30T18:25:45.711573940Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=0 off=7837 len=1306 fin=false
[2025-04-30T18:25:45.711578268Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.605797ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10286 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 5, last_sent_packet: 11, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711588377Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=3214 out_len=1350 left=1312
[2025-04-30T18:25:45.711591333Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1161 pn=12 
[2025-04-30T18:25:45.711593647Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=0 off=9143 len=1155 fin=true
[2025-04-30T18:25:45.711597975Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.608512ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=11485 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 5, last_sent_packet: 12, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711697862Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e written 10649 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:56474, at: Instant { tv_sec: 297, tv_nsec: 861162798 } })
[2025-04-30T18:25:45.711702421Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.711706037Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.711711077Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e pmtud probe status false hs_con=true hs_sent=true cwnd_avail=2015 out_len=1350 left=1312
[2025-04-30T18:25:45.711714653Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=31 pn=13 
[2025-04-30T18:25:45.711721486Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e tx frm STREAM id=15 off=0 len=26 fin=true
[2025-04-30T18:25:45.711727938Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e timer=108.599325ms latest_rtt=33.477052ms srtt=33.430589ms min_rtt=33.423953ms rttvar=12.547256ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=11554 app_limited=true congestion_recovery_start_time=None Rate { delivered: 390, delivered_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, end_of_app_limited: 5, last_sent_packet: 13, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11668, is_app_limited: true, interval: 33.423953ms, delivered: 390, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 827047371 }), send_elapsed: 0ns, ack_elapsed: 33.423953ms, rtt: 33.423953ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504777, last_update: Instant { tv_sec: 297, tv_nsec: 861162798 }, next_time: Instant { tv_sec: 297, tv_nsec: 861162798 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:25:45.711757644Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:56474, at: Instant { tv_sec: 297, tv_nsec: 861162798 } })
[2025-04-30T18:25:45.711761461Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.711765128Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.711767773Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.711769927Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.742827112Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.742848792Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.742862949Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=22 pn=8 
[2025-04-30T18:25:45.742876314Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=10 blocks=[2..4] ecn_counts=None
[2025-04-30T18:25:45.742883467Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 2
[2025-04-30T18:25:45.742889959Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 3
[2025-04-30T18:25:45.742893776Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 4
[2025-04-30T18:25:45.742922100Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 43 bytes
[2025-04-30T18:25:45.742931507Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.742936346Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.742939662Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.743666622Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.743674797Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.743680388Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=22 pn=9 
[2025-04-30T18:25:45.743685958Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=18 blocks=[2..5] ecn_counts=None
[2025-04-30T18:25:45.743689896Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 5
[2025-04-30T18:25:45.743701417Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 43 bytes
[2025-04-30T18:25:45.743714892Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.743718599Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.743721344Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.744645764Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.744651715Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.744656223Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=22 pn=10 
[2025-04-30T18:25:45.744660862Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=5 blocks=[2..6] ecn_counts=None
[2025-04-30T18:25:45.744663878Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 6
[2025-04-30T18:25:45.744673125Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 43 bytes
[2025-04-30T18:25:45.744677092Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.744679937Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.744682081Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.745752634Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.745758685Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.745762973Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=22 pn=11 
[2025-04-30T18:25:45.745767221Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=4 blocks=[2..7] ecn_counts=None
[2025-04-30T18:25:45.745770067Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 7
[2025-04-30T18:25:45.745778703Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 43 bytes
[2025-04-30T18:25:45.745782550Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.745785044Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.745787168Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.746863953Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.746869523Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.746873481Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=22 pn=12 
[2025-04-30T18:25:45.746877618Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=5 blocks=[2..8] ecn_counts=None
[2025-04-30T18:25:45.746880534Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 8
[2025-04-30T18:25:45.746888228Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 43 bytes
[2025-04-30T18:25:45.746892065Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.746894540Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.746896684Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.747959983Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.747965273Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.747969210Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=22 pn=13 
[2025-04-30T18:25:45.747973358Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=5 blocks=[2..9] ecn_counts=None
[2025-04-30T18:25:45.747976233Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 9
[2025-04-30T18:25:45.748008674Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 43 bytes
[2025-04-30T18:25:45.748014555Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.748017921Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.748020165Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.749042568Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.749049310Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.749053899Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=22 pn=14 
[2025-04-30T18:25:45.749058307Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=4 blocks=[2..10] ecn_counts=None
[2025-04-30T18:25:45.749061333Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 10
[2025-04-30T18:25:45.749068557Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 43 bytes
[2025-04-30T18:25:45.749072364Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.749074929Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.749077033Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.750155610Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.750161241Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.750165168Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=22 pn=15 
[2025-04-30T18:25:45.750169215Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm ACK delay=7 blocks=[2..11] ecn_counts=None
[2025-04-30T18:25:45.750172081Z TRACE quiche::recovery::congestion::recovery] 747b303c77f5396506b592183e6e5fc5dcf0156e packet newly acked 11
[2025-04-30T18:25:45.750179505Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 43 bytes
[2025-04-30T18:25:45.750183262Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.750185776Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.750187940Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.751137877Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:56474 to [::]:443
[2025-04-30T18:25:45.751146644Z TRACE quiche_server] got packet Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false
[2025-04-30T18:25:45.751151253Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=28 pn=16 
[2025-04-30T18:25:45.751156502Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e rx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T18:25:45.751164567Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e processed 49 bytes
[2025-04-30T18:25:45.751168705Z TRACE quiche_server] recv() would block
[2025-04-30T18:25:45.751170979Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.751173093Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.905390888Z TRACE quiche_server] timed out
[2025-04-30T18:25:45.905424010Z TRACE quiche] 747b303c77f5396506b592183e6e5fc5dcf0156e draining timeout expired
[2025-04-30T18:25:45.905471198Z TRACE quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e done writing
[2025-04-30T18:25:45.905475206Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:25:45.905478842Z INFO  quiche_server] 747b303c77f5396506b592183e6e5fc5dcf0156e connection collected recv=17 sent=14 lost=0 retrans=0 sent_bytes=11944 recv_bytes=2567 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:56474 validation_state=Validated active=true recv=17 sent=14 lost=0 retrans=0 rtt=35.066988ms min_rtt=Some(31.720486ms) rttvar=4.014628ms cwnd=13500 sent_bytes=11944 recv_bytes=2567 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=324269]