[2025-04-30T10:21:56.327837058Z INFO  quiche_server] listening on [::]:443
[2025-04-30T10:21:56.327902931Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T10:21:56.327910886Z TRACE quiche_server] GSO detected: false
[2025-04-30T10:21:57.117994231Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:57409 to [::]:443
[2025-04-30T10:21:57.118024407Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:21:57.118031561Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:21:57.118111490Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:57409 to [::]:443
[2025-04-30T10:21:57.118117051Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:21:57.118120177Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:21:57.118140074Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:57409 to [::]:443
[2025-04-30T10:21:57.118145915Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:21:57.118149922Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:21:57.118178115Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.407755964Z TRACE quiche_server] got 1200 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.407783135Z TRACE quiche_server] got packet Initial version=1 dcid=9e60b433ea41e9d85900b62589c63559f368 scid=7ca2234776755a17edef9592e2c605c7ad token=
[2025-04-30T10:21:57.407792773Z DEBUG quiche_server] New connection: dcid=9e60b433ea41e9d85900b62589c63559f368 scid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693
[2025-04-30T10:21:57.408040807Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Initial version=1 dcid=9e60b433ea41e9d85900b62589c63559f368 scid=7ca2234776755a17edef9592e2c605c7ad token= len=1153 pn=588356144 
[2025-04-30T10:21:57.408060364Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm CRYPTO off=0 len=263
[2025-04-30T10:21:57.408090230Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T10:21:57.408094918Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T10:21:57.408177183Z TRACE quiche::tls] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 write message lvl=Initial len=90
[2025-04-30T10:21:57.408209343Z TRACE quiche::tls] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 set write secret lvl=Handshake
[2025-04-30T10:21:57.408272932Z TRACE quiche::tls] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 write message lvl=Handshake len=708
[2025-04-30T10:21:57.408275987Z TRACE quiche::tls] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 set write secret lvl=OneRTT
[2025-04-30T10:21:57.408282219Z TRACE quiche::tls] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 set read secret lvl=Handshake
[2025-04-30T10:21:57.408299612Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm PADDING len=866
[2025-04-30T10:21:57.408317074Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 1200 bytes
[2025-04-30T10:21:57.408332924Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.408345798Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Initial version=1 dcid=7ca2234776755a17edef9592e2c605c7ad scid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 len=102 pn=0 
[2025-04-30T10:21:57.408349205Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm ACK delay=40 blocks=[588356144..588356144] ecn_counts=None
[2025-04-30T10:21:57.408353763Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm CRYPTO off=0 len=90
[2025-04-30T10:21:57.408362670Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=998.971878ms 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: 807, tv_nsec: 633489151 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 633489151 }, 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: 807, tv_nsec: 633489151 }, next_time: Instant { tv_sec: 807, tv_nsec: 633489151 }, 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-30T10:21:57.408392415Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Handshake version=1 dcid=7ca2234776755a17edef9592e2c605c7ad scid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 len=712 pn=1 
[2025-04-30T10:21:57.408395331Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm CRYPTO off=0 len=708
[2025-04-30T10:21:57.408400761Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=998.934177ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=941 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 807, tv_nsec: 633489151 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 633489151 }, 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: 807, tv_nsec: 633489151 }, next_time: Instant { tv_sec: 807, tv_nsec: 633489151 }, 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-30T10:21:57.408443962Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [fd00:cafe:cafe::100]:54241, at: Instant { tv_sec: 807, tv_nsec: 633489151 } })
[2025-04-30T10:21:57.408453169Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.408459912Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.408465292Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.408469269Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.442526182Z TRACE quiche_server] got 1200 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.442544266Z TRACE quiche_server] got packet Initial version=1 dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 scid=7ca2234776755a17edef9592e2c605c7ad token=
[2025-04-30T10:21:57.442556248Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Initial version=1 dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 scid=7ca2234776755a17edef9592e2c605c7ad token= len=22 pn=588356145 
[2025-04-30T10:21:57.442565656Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T10:21:57.442571237Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 0
[2025-04-30T10:21:57.442594330Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Handshake version=1 dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 scid=7ca2234776755a17edef9592e2c605c7ad len=64 pn=588356144 
[2025-04-30T10:21:57.442599810Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=0 blocks=[1..1] ecn_counts=None
[2025-04-30T10:21:57.442602686Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 1
[2025-04-30T10:21:57.442612644Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm CRYPTO off=0 len=36
[2025-04-30T10:21:57.442626760Z TRACE quiche::tls] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 set read secret lvl=OneRTT
[2025-04-30T10:21:57.442657097Z TRACE quiche::tls] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 write message lvl=OneRTT len=396
[2025-04-30T10:21:57.442665513Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 dropped epoch 1 state
[2025-04-30T10:21:57.442668970Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 connection established: proto=Ok("hq-interop") 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: 65527, initial_max_data: 25165824, initial_max_stream_data_bidi_local: 16777216, initial_max_stream_data_bidi_remote: 0, initial_max_stream_data_uni: 16777216, initial_max_streams_bidi: 0, initial_max_streams_uni: 0, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 7, initial_source_connection_id: Some(7ca2234776755a17edef9592e2c605c7ad), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T10:21:57.442692283Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 dropped epoch 0 state
[2025-04-30T10:21:57.442695319Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=996 pn=588356144 
[2025-04-30T10:21:57.442702041Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[fc, 66, 0a, ec, ab, ab, ea, f0, e9, 37, 59, 85, ed, fb, f6, 0b, d3] reset_token=[9a, 7a, 3e, 5c, c8, 3a, ab, 1b, e2, d5, d2, f2, ac, fc, a0, d4]
[2025-04-30T10:21:57.442709836Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[7b, 9a, f7, 92, 1c, fd, 79, cb, 28, 53, 3b, f4, c4, 8f, d2, 1b, 77] reset_token=[fe, 1b, c5, 8b, 5e, a9, ea, 34, d4, 54, 46, ac, d5, d6, 29, f2]
[2025-04-30T10:21:57.442715807Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[e3, d8, aa, 09, 7d, 06, 89, b1, 4c, eb, 67, af, 7b, b7, 6a, c0, 53] reset_token=[8e, e7, 0f, b4, 24, 88, 74, 34, 51, e8, 07, 90, 90, 09, f1, ec]
[2025-04-30T10:21:57.442721718Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[95, 60, e7, 0a, ba, 36, f3, 7a, 2e, 7f, 4c, 3a, e3, eb, 19, 2c, 2e] reset_token=[05, 4e, 93, ac, 63, a7, fe, 21, 02, cc, a7, f8, e7, 6b, 78, 25]
[2025-04-30T10:21:57.442727900Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[fd, 6d, ca, bd, f8, 91, fa, 41, a0, b1, b1, 2d, 03, 7f, cd, 89, 8b] reset_token=[35, 04, d6, 03, 24, 86, 00, 69, 00, 99, b0, bd, f0, a4, 6c, a7]
[2025-04-30T10:21:57.442733781Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[e4, 23, 2a, 78, a5, 1f, a3, 4d, 27, 62, 0b, 8a, 56, 61, a2, a7, a6] reset_token=[80, 33, bd, 13, af, c2, 40, aa, 83, 3d, 49, bf, b8, 90, c5, 55]
[2025-04-30T10:21:57.442739402Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[97, 7b, 59, e4, 85, 6e, fd, 60, 38, 44, 23, 19, 4a, a2, 8c, 05, 82] reset_token=[4c, ab, 88, 01, a6, c2, d6, 81, cc, c7, b6, f4, b3, 52, e1, 75]
[2025-04-30T10:21:57.442744020Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T10:21:57.442748278Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm STREAM id=4 off=0 len=17 fin=true
[2025-04-30T10:21:57.442753197Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm PADDING len=677
[2025-04-30T10:21:57.442762104Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 1200 bytes
[2025-04-30T10:21:57.442770329Z DEBUG quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 response stream 0 is writable with capacity Ok(13500)
[2025-04-30T10:21:57.442773936Z DEBUG quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 response stream 4 is writable with capacity Ok(13500)
[2025-04-30T10:21:57.442779336Z TRACE quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 received 17 bytes
[2025-04-30T10:21:57.442781971Z TRACE quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 stream 0 has 17 bytes (fin? true)
[2025-04-30T10:21:57.442786269Z INFO  quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 got GET request for "/www/dxgmjebnwt" on stream 0
[2025-04-30T10:21:57.442810484Z INFO  quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 sending response of size 5120 on stream 0
[2025-04-30T10:21:57.442817267Z TRACE quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 received 17 bytes
[2025-04-30T10:21:57.442819601Z TRACE quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 stream 4 has 17 bytes (fin? true)
[2025-04-30T10:21:57.442822246Z INFO  quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 got GET request for "/www/zlvmtwkczy" on stream 4
[2025-04-30T10:21:57.442835021Z INFO  quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 sending response of size 10240 on stream 4
[2025-04-30T10:21:57.442842184Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.442874865Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T10:21:57.442891246Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=2 
[2025-04-30T10:21:57.442894372Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm ACK delay=22 blocks=[588356144..588356144] ecn_counts=None
[2025-04-30T10:21:57.442897988Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[40, 42, a1, 3e, 48, 16, ba, fa, 1a, 34, e2, 21, cd, 74, e7, b8, 49, 89, dd, be] reset_token=[cd, 14, 94, fe, 91, df, c1, b3, 89, d6, 6b, 34, 12, 80, 2a, b8]
[2025-04-30T10:21:57.442904250Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[04, 5b, c8, 21, cd, 02, 60, 4e, a3, 30, 93, 94, 15, 75, 10, 03, 3e, 01, 32, 52] reset_token=[21, 74, 31, 72, 89, 2f, cc, 61, 3f, cb, 63, 48, e4, f2, e5, af]
[2025-04-30T10:21:57.442910301Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[d5, 8b, c6, 39, 62, 09, 81, ba, 9b, 86, 16, e6, bc, ac, da, 28, 77, e8, ee, f5] reset_token=[59, 78, 52, fd, e0, ff, 30, b3, 09, be, e1, 89, 98, a7, fc, 82]
[2025-04-30T10:21:57.442916202Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[e0, b7, 67, 28, bb, 64, b9, 9c, a8, f0, 14, 33, 1e, 12, d5, fa, e8, e5, 44, 1d] reset_token=[35, 06, f0, f0, d7, 8a, 9f, a0, ad, 45, 62, 3e, 86, 5f, 13, 80]
[2025-04-30T10:21:57.442921893Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[7c, ec, 7c, 1a, 50, 06, 24, a3, 3c, 59, b1, c1, a4, 42, 85, 92, da, 95, 1c, f2] reset_token=[ff, e3, c2, 8f, a5, 07, a2, 31, 58, 1d, 43, c1, 18, 02, fa, 19]
[2025-04-30T10:21:57.442927574Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[f0, a9, da, d5, a7, 9e, 58, 66, 44, a3, 36, 38, 2e, 4c, ff, 2a, f0, c0, 36, 37] reset_token=[16, 40, 65, 87, 94, 1b, 08, a5, 84, e4, dc, d8, 78, 2e, b1, 1a]
[2025-04-30T10:21:57.442933134Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm HANDSHAKE_DONE
[2025-04-30T10:21:57.442935449Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm CRYPTO off=0 len=396
[2025-04-30T10:21:57.442943553Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=0 off=0 len=661 fin=false
[2025-04-30T10:21:57.442956528Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.477663ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.442991243Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12150 out_len=1350 left=1315
[2025-04-30T10:21:57.442995170Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=3 
[2025-04-30T10:21:57.442997735Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=0 len=1310 fin=false
[2025-04-30T10:21:57.443003275Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.575446ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2700 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443015398Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10800 out_len=1350 left=1315
[2025-04-30T10:21:57.443018644Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=4 
[2025-04-30T10:21:57.443021009Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=0 off=661 len=1309 fin=false
[2025-04-30T10:21:57.443025367Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.578492ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4050 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443039363Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9450 out_len=1350 left=1315
[2025-04-30T10:21:57.443042579Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=5 
[2025-04-30T10:21:57.443044953Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=1310 len=1309 fin=false
[2025-04-30T10:21:57.443049121Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.578892ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443059821Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8100 out_len=1350 left=1315
[2025-04-30T10:21:57.443062777Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=6 
[2025-04-30T10:21:57.443065101Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=0 off=1970 len=1309 fin=false
[2025-04-30T10:21:57.443069429Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.579052ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6750 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443082925Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=6750 out_len=1350 left=1315
[2025-04-30T10:21:57.443085920Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=7 
[2025-04-30T10:21:57.443088244Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=2619 len=1309 fin=false
[2025-04-30T10:21:57.443092563Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.578962ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8100 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443103142Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=5400 out_len=1350 left=1315
[2025-04-30T10:21:57.443106038Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=8 
[2025-04-30T10:21:57.443108342Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=0 off=3279 len=1309 fin=false
[2025-04-30T10:21:57.443112560Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.579323ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9450 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443125765Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4050 out_len=1350 left=1315
[2025-04-30T10:21:57.443128750Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=9 
[2025-04-30T10:21:57.443131075Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=3928 len=1309 fin=false
[2025-04-30T10:21:57.443135202Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.579293ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10800 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443145762Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=2700 out_len=1350 left=1315
[2025-04-30T10:21:57.443148537Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=538 pn=10 
[2025-04-30T10:21:57.443150902Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=0 off=4588 len=532 fin=true
[2025-04-30T10:21:57.443155330Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.579232ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=11373 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 10, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443277228Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 written 11373 bytes with Some(SendInfo { from: [::]:443, to: [fd00:cafe:cafe::100]:54241, at: Instant { tv_sec: 807, tv_nsec: 667998431 } })
[2025-04-30T10:21:57.443286375Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.443292907Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.443300542Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=2127 out_len=1350 left=1315
[2025-04-30T10:21:57.443308948Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=11 
[2025-04-30T10:21:57.443311733Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=5237 len=1309 fin=false
[2025-04-30T10:21:57.443319016Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.567631ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=12723 app_limited=true congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 11, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443331399Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=777 out_len=1350 left=742
[2025-04-30T10:21:57.443334315Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=742 pn=12 
[2025-04-30T10:21:57.443336740Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=6546 len=736 fin=false
[2025-04-30T10:21:57.443340957Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.578932ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=13500 app_limited=false congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 12, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443381223Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 written 2127 bytes with Some(SendInfo { from: [::]:443, to: [fd00:cafe:cafe::100]:54241, at: Instant { tv_sec: 807, tv_nsec: 667998431 } })
[2025-04-30T10:21:57.443389128Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.443394568Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.443400719Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=0 out_len=1350 left=0
[2025-04-30T10:21:57.443411580Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.443414074Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.443600714Z TRACE quiche_server] got 1342 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.443604691Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.443610582Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=1321 pn=588356145 
[2025-04-30T10:21:57.443616132Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm PING mtu_probe=None
[2025-04-30T10:21:57.443622374Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm PADDING len=1300
[2025-04-30T10:21:57.443628636Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 1342 bytes
[2025-04-30T10:21:57.443632423Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.443636781Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=0 out_len=1350 left=0
[2025-04-30T10:21:57.443639506Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=8 pn=13 
[2025-04-30T10:21:57.443641871Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm ACK delay=3 blocks=[588356144..588356145] ecn_counts=None
[2025-04-30T10:21:57.443648172Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=110.271627ms latest_rtt=34.2584ms srtt=34.222843ms min_rtt=34.217764ms rttvar=12.84182ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=13500 app_limited=false congestion_recovery_start_time=None Rate { delivered: 941, delivered_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 1, last_sent_packet: 13, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27500, is_app_limited: true, interval: 34.217764ms, delivered: 941, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 633489151 }), send_elapsed: 0ns, ack_elapsed: 34.217764ms, rtt: 34.217764ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 493091, last_update: Instant { tv_sec: 807, tv_nsec: 667998431 }, next_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, 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-30T10:21:57.443677347Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 written 43 bytes with Some(SendInfo { from: [::]:443, to: [fd00:cafe:cafe::100]:54241, at: Instant { tv_sec: 807, tv_nsec: 667998431 } })
[2025-04-30T10:21:57.443684741Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.443689830Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.443696173Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=0 out_len=1350 left=0
[2025-04-30T10:21:57.443701092Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.443704488Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.475935363Z TRACE quiche_server] got 43 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.475953286Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.475965199Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=22 pn=588356146 
[2025-04-30T10:21:57.475976310Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=6 blocks=[2..3] ecn_counts=None
[2025-04-30T10:21:57.475995676Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 2
[2025-04-30T10:21:57.476000855Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 3
[2025-04-30T10:21:57.476024219Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 43 bytes
[2025-04-30T10:21:57.476028197Z DEBUG quiche_apps::common] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 response stream 4 is writable with capacity Ok(5400)
[2025-04-30T10:21:57.476037775Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.476044557Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=5400 out_len=1350 left=1315
[2025-04-30T10:21:57.476051510Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=14 
[2025-04-30T10:21:57.476054095Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=7282 len=1309 fin=false
[2025-04-30T10:21:57.476063222Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=98.691746ms latest_rtt=33.118115ms srtt=34.084751ms min_rtt=33.118115ms rttvar=9.907547ms cwnd=16200 ssthresh=18446744073709551615 bytes_in_flight=12150 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3641, delivered_time: Instant { tv_sec: 807, tv_nsec: 701116546 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 0, last_sent_packet: 14, largest_acked: 3, rate_sample: RateSample { delivery_rate: 81526, is_app_limited: true, interval: 33.118115ms, delivered: 2700, prior_delivered: 941, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 667998431 }), send_elapsed: 0ns, ack_elapsed: 33.118115ms, rtt: 33.118115ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 594107, last_update: Instant { tv_sec: 807, tv_nsec: 701194312 }, next_time: Instant { tv_sec: 807, tv_nsec: 701194312 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(14) last_round_min_rtt=33.118115ms 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-30T10:21:57.476080745Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4050 out_len=1350 left=1315
[2025-04-30T10:21:57.476084182Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=1315 pn=15 
[2025-04-30T10:21:57.476086616Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=8591 len=1309 fin=false
[2025-04-30T10:21:57.476091465Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=98.702466ms latest_rtt=33.118115ms srtt=34.084751ms min_rtt=33.118115ms rttvar=9.907547ms cwnd=16200 ssthresh=18446744073709551615 bytes_in_flight=13500 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3641, delivered_time: Instant { tv_sec: 807, tv_nsec: 701116546 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 0, last_sent_packet: 15, largest_acked: 3, rate_sample: RateSample { delivery_rate: 81526, is_app_limited: true, interval: 33.118115ms, delivered: 2700, prior_delivered: 941, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 667998431 }), send_elapsed: 0ns, ack_elapsed: 33.118115ms, rtt: 33.118115ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 594107, last_update: Instant { tv_sec: 807, tv_nsec: 701194312 }, next_time: Instant { tv_sec: 807, tv_nsec: 701194312 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(14) last_round_min_rtt=33.118115ms 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-30T10:21:57.476106974Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=2700 out_len=1350 left=1315
[2025-04-30T10:21:57.476110100Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx pkt Short dcid=7ca2234776755a17edef9592e2c605c7ad key_phase=false len=346 pn=16 
[2025-04-30T10:21:57.476112535Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 tx frm STREAM id=4 off=9900 len=340 fin=true
[2025-04-30T10:21:57.476117003Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 timer=98.703427ms latest_rtt=33.118115ms srtt=34.084751ms min_rtt=33.118115ms rttvar=9.907547ms cwnd=16200 ssthresh=18446744073709551615 bytes_in_flight=13881 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3641, delivered_time: Instant { tv_sec: 807, tv_nsec: 701116546 }, first_sent_time: Instant { tv_sec: 807, tv_nsec: 667998431 }, end_of_app_limited: 0, last_sent_packet: 16, largest_acked: 3, rate_sample: RateSample { delivery_rate: 81526, is_app_limited: true, interval: 33.118115ms, delivered: 2700, prior_delivered: 941, prior_time: Some(Instant { tv_sec: 807, tv_nsec: 667998431 }), send_elapsed: 0ns, ack_elapsed: 33.118115ms, rtt: 33.118115ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 594107, last_update: Instant { tv_sec: 807, tv_nsec: 701194312 }, next_time: Instant { tv_sec: 807, tv_nsec: 701194312 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(14) last_round_min_rtt=33.118115ms 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-30T10:21:57.476179540Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 written 3081 bytes with Some(SendInfo { from: [::]:443, to: [fd00:cafe:cafe::100]:54241, at: Instant { tv_sec: 807, tv_nsec: 701194312 } })
[2025-04-30T10:21:57.476189318Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.476195931Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.476199868Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.476203134Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.478136153Z TRACE quiche_server] got 43 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.478143156Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.478148816Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=22 pn=588356147 
[2025-04-30T10:21:57.478154177Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=7 blocks=[2..5] ecn_counts=None
[2025-04-30T10:21:57.478157613Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 4
[2025-04-30T10:21:57.478160057Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 5
[2025-04-30T10:21:57.478169996Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 43 bytes
[2025-04-30T10:21:57.478173954Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.478176418Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.478178592Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.480361248Z TRACE quiche_server] got 43 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.480368051Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.480373160Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=22 pn=588356148 
[2025-04-30T10:21:57.480384151Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=5 blocks=[2..7] ecn_counts=None
[2025-04-30T10:21:57.480387767Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 6
[2025-04-30T10:21:57.480390593Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 7
[2025-04-30T10:21:57.480400892Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 43 bytes
[2025-04-30T10:21:57.480405451Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.480408486Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.480411171Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.482594468Z TRACE quiche_server] got 43 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.482601251Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.482606140Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=22 pn=588356149 
[2025-04-30T10:21:57.482611060Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=6 blocks=[2..9] ecn_counts=None
[2025-04-30T10:21:57.482614456Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 8
[2025-04-30T10:21:57.482617281Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 9
[2025-04-30T10:21:57.482626909Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 43 bytes
[2025-04-30T10:21:57.482631187Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.482634112Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.482636788Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.484195095Z TRACE quiche_server] got 43 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.484202639Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.484207678Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=22 pn=588356150 
[2025-04-30T10:21:57.484212868Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=5 blocks=[2..11] ecn_counts=None
[2025-04-30T10:21:57.484216515Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 10
[2025-04-30T10:21:57.484219451Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 11
[2025-04-30T10:21:57.484245168Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 43 bytes
[2025-04-30T10:21:57.484249787Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.484252913Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.484255638Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.490063230Z TRACE quiche_server] got 44 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.490071475Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.490077005Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=23 pn=588356151 
[2025-04-30T10:21:57.490082676Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=642 blocks=[2..13] ecn_counts=None
[2025-04-30T10:21:57.490086493Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 12
[2025-04-30T10:21:57.490089479Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 13
[2025-04-30T10:21:57.490104717Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 44 bytes
[2025-04-30T10:21:57.490109566Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.490112782Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.490115447Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.508933326Z TRACE quiche_server] got 43 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.508954796Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.508970946Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=22 pn=588356152 
[2025-04-30T10:21:57.508985904Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm ACK delay=5 blocks=[2..15] ecn_counts=None
[2025-04-30T10:21:57.508994009Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 14
[2025-04-30T10:21:57.508998448Z TRACE quiche::recovery::congestion::recovery] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 packet newly acked 15
[2025-04-30T10:21:57.509027863Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 43 bytes
[2025-04-30T10:21:57.509037140Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.509041548Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.509044514Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.509174267Z TRACE quiche_server] got 42 bytes from [fd00:cafe:cafe::100]:54241 to [::]:443
[2025-04-30T10:21:57.509181450Z TRACE quiche_server] got packet Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false
[2025-04-30T10:21:57.509186770Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx pkt Short dcid=c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 key_phase=false len=21 pn=588356153 
[2025-04-30T10:21:57.509192982Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T10:21:57.509201037Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 processed 42 bytes
[2025-04-30T10:21:57.509207279Z TRACE quiche_server] recv() would block
[2025-04-30T10:21:57.509210785Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.509214432Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.685423547Z TRACE quiche_server] timed out
[2025-04-30T10:21:57.685460757Z TRACE quiche] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 draining timeout expired
[2025-04-30T10:21:57.685509198Z TRACE quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 done writing
[2025-04-30T10:21:57.685514648Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:21:57.685520018Z INFO  quiche_server] c5c1a9016e82fc86f0e59aaabe7ddbc3e3966693 connection collected recv=13 sent=17 lost=0 retrans=0 sent_bytes=17565 recv_bytes=4086 lost_bytes=0 [local_addr=[::]:443 peer_addr=[fd00:cafe:cafe::100]:54241 validation_state=Validated active=true recv=13 sent=17 lost=0 retrans=0 rtt=36.304103ms min_rtt=Some(32.926499ms) rttvar=5.578967ms cwnd=16200 sent_bytes=17565 recv_bytes=4086 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=406676]