[2025-04-30T10:13:27.574435349Z INFO  quiche_server] listening on [::]:443
[2025-04-30T10:13:27.574456659Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T10:13:27.574462841Z TRACE quiche_server] GSO detected: false
[2025-04-30T10:13:28.382034104Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:55304 to [::]:443
[2025-04-30T10:13:28.382073448Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:13:28.382084468Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:13:28.382180919Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:55304 to [::]:443
[2025-04-30T10:13:28.382190897Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:13:28.382196889Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:13:28.382228859Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:55304 to [::]:443
[2025-04-30T10:13:28.382235812Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T10:13:28.382241322Z WARN  quiche_server] Doing version negotiation
[2025-04-30T10:13:28.382272981Z TRACE quiche_server] recv() would block
[2025-04-30T10:13:28.653074374Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:51250 to [::]:443
[2025-04-30T10:13:28.653104040Z TRACE quiche_server] got packet Initial version=1 dcid=eb0b38fd4d4643999ac7573d59a5959b4dc0 scid=d255207b84f70e991cda4b7a77b1e0f3b5 token=
[2025-04-30T10:13:28.653115181Z DEBUG quiche_server] New connection: dcid=eb0b38fd4d4643999ac7573d59a5959b4dc0 scid=f398ea2975fb1af662104957ac43f5ebb64c0d64
[2025-04-30T10:13:28.653346294Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx pkt Initial version=1 dcid=eb0b38fd4d4643999ac7573d59a5959b4dc0 scid=d255207b84f70e991cda4b7a77b1e0f3b5 token= len=1153 pn=1952395025 
[2025-04-30T10:13:28.653361122Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm CRYPTO off=0 len=263
[2025-04-30T10:13:28.653390637Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T10:13:28.653395286Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T10:13:28.653496595Z TRACE quiche::tls] f398ea2975fb1af662104957ac43f5ebb64c0d64 write message lvl=Initial len=90
[2025-04-30T10:13:28.653529397Z TRACE quiche::tls] f398ea2975fb1af662104957ac43f5ebb64c0d64 set write secret lvl=Handshake
[2025-04-30T10:13:28.653603846Z TRACE quiche::tls] f398ea2975fb1af662104957ac43f5ebb64c0d64 write message lvl=Handshake len=708
[2025-04-30T10:13:28.653606742Z TRACE quiche::tls] f398ea2975fb1af662104957ac43f5ebb64c0d64 set write secret lvl=OneRTT
[2025-04-30T10:13:28.653612853Z TRACE quiche::tls] f398ea2975fb1af662104957ac43f5ebb64c0d64 set read secret lvl=Handshake
[2025-04-30T10:13:28.653630496Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm PADDING len=866
[2025-04-30T10:13:28.653648610Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 processed 1200 bytes
[2025-04-30T10:13:28.653664540Z TRACE quiche_server] recv() would block
[2025-04-30T10:13:28.653676662Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx pkt Initial version=1 dcid=d255207b84f70e991cda4b7a77b1e0f3b5 scid=f398ea2975fb1af662104957ac43f5ebb64c0d64 len=106 pn=0 
[2025-04-30T10:13:28.653680119Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm ACK delay=43 blocks=[1952395025..1952395025] ecn_counts=None
[2025-04-30T10:13:28.653684487Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm CRYPTO off=0 len=90
[2025-04-30T10:13:28.653693213Z TRACE quiche::recovery::congestion::recovery] f398ea2975fb1af662104957ac43f5ebb64c0d64 timer=998.973059ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=170 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 298, tv_nsec: 878820776 }, first_sent_time: Instant { tv_sec: 298, tv_nsec: 878820776 }, 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: 298, tv_nsec: 878820776 }, next_time: Instant { tv_sec: 298, tv_nsec: 878820776 }, 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:13:28.653721266Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx pkt Handshake version=1 dcid=d255207b84f70e991cda4b7a77b1e0f3b5 scid=f398ea2975fb1af662104957ac43f5ebb64c0d64 len=712 pn=1 
[2025-04-30T10:13:28.653724191Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm CRYPTO off=0 len=708
[2025-04-30T10:13:28.653729672Z TRACE quiche::recovery::congestion::recovery] f398ea2975fb1af662104957ac43f5ebb64c0d64 timer=998.936891ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=945 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 298, tv_nsec: 878820776 }, first_sent_time: Instant { tv_sec: 298, tv_nsec: 878820776 }, 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: 298, tv_nsec: 878820776 }, next_time: Instant { tv_sec: 298, tv_nsec: 878820776 }, 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:13:28.653784364Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:51250, at: Instant { tv_sec: 298, tv_nsec: 878820776 } })
[2025-04-30T10:13:28.653793271Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:13:28.653800114Z TRACE quiche_server] recv() would block
[2025-04-30T10:13:28.653805564Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 done writing
[2025-04-30T10:13:28.653809211Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:13:28.688108257Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:51250 to [::]:443
[2025-04-30T10:13:28.688129396Z TRACE quiche_server] got packet Initial version=1 dcid=f398ea2975fb1af662104957ac43f5ebb64c0d64 scid=d255207b84f70e991cda4b7a77b1e0f3b5 token=
[2025-04-30T10:13:28.688143473Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx pkt Initial version=1 dcid=f398ea2975fb1af662104957ac43f5ebb64c0d64 scid=d255207b84f70e991cda4b7a77b1e0f3b5 token= len=22 pn=1952395026 
[2025-04-30T10:13:28.688155235Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T10:13:28.688161837Z TRACE quiche::recovery::congestion::recovery] f398ea2975fb1af662104957ac43f5ebb64c0d64 packet newly acked 0
[2025-04-30T10:13:28.688186964Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx pkt Handshake version=1 dcid=f398ea2975fb1af662104957ac43f5ebb64c0d64 scid=d255207b84f70e991cda4b7a77b1e0f3b5 len=64 pn=1952395025 
[2025-04-30T10:13:28.688193246Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm ACK delay=0 blocks=[1..1] ecn_counts=None
[2025-04-30T10:13:28.688196682Z TRACE quiche::recovery::congestion::recovery] f398ea2975fb1af662104957ac43f5ebb64c0d64 packet newly acked 1
[2025-04-30T10:13:28.688209486Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm CRYPTO off=0 len=36
[2025-04-30T10:13:28.688228492Z TRACE quiche::tls] f398ea2975fb1af662104957ac43f5ebb64c0d64 set read secret lvl=OneRTT
[2025-04-30T10:13:28.688266032Z TRACE quiche::tls] f398ea2975fb1af662104957ac43f5ebb64c0d64 write message lvl=OneRTT len=396
[2025-04-30T10:13:28.688276261Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 dropped epoch 1 state
[2025-04-30T10:13:28.688281050Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 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(d255207b84f70e991cda4b7a77b1e0f3b5), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T10:13:28.688303663Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 dropped epoch 0 state
[2025-04-30T10:13:28.688307860Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx pkt Short dcid=f398ea2975fb1af662104957ac43f5ebb64c0d64 key_phase=false len=996 pn=1952395025 
[2025-04-30T10:13:28.688315445Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[57, c9, 33, 2e, 4a, 78, 6d, 9d, 7e, ec, 11, 83, 13, af, 35, 59, bc] reset_token=[f8, 44, cf, 80, f6, fd, b0, e8, 23, 98, 15, 60, 33, 75, 22, 6f]
[2025-04-30T10:13:28.688324722Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[48, ac, 8c, d7, cc, ac, 04, 52, 66, e6, dc, 3f, fb, 2b, 5e, 25, 96] reset_token=[31, 29, e3, 3f, dc, dc, 2c, 21, 23, 79, 11, 93, 6a, 47, 6a, f4]
[2025-04-30T10:13:28.688332226Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[0e, 92, 34, fc, 15, 24, 40, 78, 77, 54, e8, 2b, 28, 71, 6b, 03, b1] reset_token=[38, ab, aa, c3, 2f, 58, 3c, 28, 2c, 7f, d0, 8a, 1e, bc, a2, 18]
[2025-04-30T10:13:28.688338598Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[ab, 62, 6c, b8, 38, 7b, ba, c4, 4a, 69, 1d, e0, d7, e4, 90, 9b, 2a] reset_token=[bc, 5a, f6, ad, 3c, 2d, 6c, 6b, 49, 80, 7e, 33, 45, 8d, b7, 70]
[2025-04-30T10:13:28.688345431Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[91, 71, c8, df, e4, 8b, 6b, 63, 50, 26, 02, 05, 0d, 40, c3, f1, 8a] reset_token=[cb, 5e, 0a, 7b, e2, 35, fc, c5, 08, 9c, 26, 6f, f0, bb, f7, 25]
[2025-04-30T10:13:28.688352003Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[55, 73, 64, 32, 22, 1f, 69, d9, c6, eb, ac, a3, 6b, 74, 59, 37, bb] reset_token=[ea, 12, 6b, 2c, dd, 7a, 13, 49, 95, 51, d4, bf, 12, 5c, cd, 8c]
[2025-04-30T10:13:28.688358235Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[d7, d2, 92, 31, 40, 68, d8, b7, dd, 44, 7c, 7c, cf, c4, ca, 74, fb] reset_token=[8f, cb, 1a, c2, 76, c7, 38, 5a, 98, 10, 87, da, af, 2e, 0e, f0]
[2025-04-30T10:13:28.688363355Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T10:13:28.688370939Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm PADDING len=697
[2025-04-30T10:13:28.688381859Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 processed 1200 bytes
[2025-04-30T10:13:28.688386528Z DEBUG quiche_apps::common] f398ea2975fb1af662104957ac43f5ebb64c0d64 response stream 0 is writable with capacity Ok(13500)
[2025-04-30T10:13:28.688399051Z TRACE quiche_apps::common] f398ea2975fb1af662104957ac43f5ebb64c0d64 received 17 bytes
[2025-04-30T10:13:28.688402368Z TRACE quiche_apps::common] f398ea2975fb1af662104957ac43f5ebb64c0d64 stream 0 has 17 bytes (fin? true)
[2025-04-30T10:13:28.688407718Z INFO  quiche_apps::common] f398ea2975fb1af662104957ac43f5ebb64c0d64 got GET request for "/www/acaqfbmowj" on stream 0
[2025-04-30T10:13:28.688435960Z INFO  quiche_apps::common] f398ea2975fb1af662104957ac43f5ebb64c0d64 sending response of size 1024 on stream 0
[2025-04-30T10:13:28.688444547Z TRACE quiche_server] recv() would block
[2025-04-30T10:13:28.688454275Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1303
[2025-04-30T10:13:28.688464594Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx pkt Short dcid=d255207b84f70e991cda4b7a77b1e0f3b5 key_phase=false len=1315 pn=2 
[2025-04-30T10:13:28.688468211Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm ACK delay=18 blocks=[1952395025..1952395025] ecn_counts=None
[2025-04-30T10:13:28.688472228Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[36, 4f, 5b, 09, 2a, cb, 22, ea, f4, 70, 83, f4, fb, 96, ac, 4f, 7d, 9c, d5, d0] reset_token=[85, 7f, 17, 0d, 59, a0, 1b, 33, 17, cd, 36, 34, a3, c6, c1, 76]
[2025-04-30T10:13:28.688478991Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[0f, e2, f1, c1, bf, f0, 3c, f9, 0b, 3f, 3b, a1, 16, 2d, 72, 6c, df, d0, 4b, 73] reset_token=[3e, a6, 88, da, 8d, bf, 37, a1, 68, 45, 20, 87, a8, 8d, b1, 20]
[2025-04-30T10:13:28.688485243Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[a8, 3f, 80, 1b, 1f, 43, e4, 7d, 74, de, 03, 67, 2f, 21, a0, e4, ee, 8c, 2b, 6d] reset_token=[fa, 1c, 5a, a5, b6, 03, 3d, 08, d8, 6b, 36, e5, c1, ec, c9, 57]
[2025-04-30T10:13:28.688491544Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[f5, 05, af, cd, 66, d0, 3f, 0e, 85, 85, 72, 08, 83, 13, c3, c1, 19, e6, 2b, 98] reset_token=[b5, 56, 52, a8, 37, 8d, a3, 41, 43, 5c, e8, 94, 2a, 66, b9, 70]
[2025-04-30T10:13:28.688497967Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[20, be, fe, 20, 63, 54, d2, 20, 44, 70, 88, 17, 5e, 74, e0, d4, 53, 8c, 0d, 43] reset_token=[4e, 60, 8c, 80, 9b, cc, 8e, cb, 22, 07, d3, cc, fd, 81, 61, 52]
[2025-04-30T10:13:28.688504419Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[a2, ec, 2a, 6d, e7, 5c, 66, ec, 8e, c1, 30, be, e8, 8d, a6, 12, 9c, d3, 3f, 87] reset_token=[49, c2, 9a, ca, 28, 77, a6, e9, 7b, 74, 3c, 83, 1d, 00, 4e, ca]
[2025-04-30T10:13:28.688510650Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm HANDSHAKE_DONE
[2025-04-30T10:13:28.688513516Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm CRYPTO off=0 len=396
[2025-04-30T10:13:28.688516451Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm STREAM id=0 off=0 len=657 fin=false
[2025-04-30T10:13:28.688530798Z TRACE quiche::recovery::congestion::recovery] f398ea2975fb1af662104957ac43f5ebb64c0d64 timer=111.14973ms latest_rtt=34.519299ms srtt=34.478192ms min_rtt=34.472321ms rttvar=12.938864ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 945, delivered_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, first_sent_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27413, is_app_limited: true, interval: 34.472321ms, delivered: 945, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 298, tv_nsec: 878820776 }), send_elapsed: 0ns, ack_elapsed: 34.472321ms, rtt: 34.472321ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 489439, last_update: Instant { tv_sec: 298, tv_nsec: 913601444 }, next_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, 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:13:28.688555324Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12150 out_len=1350 left=1315
[2025-04-30T10:13:28.688559732Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx pkt Short dcid=d255207b84f70e991cda4b7a77b1e0f3b5 key_phase=false len=373 pn=3 
[2025-04-30T10:13:28.688562878Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm STREAM id=0 off=657 len=367 fin=true
[2025-04-30T10:13:28.688569130Z TRACE quiche::recovery::congestion::recovery] f398ea2975fb1af662104957ac43f5ebb64c0d64 timer=111.217087ms latest_rtt=34.519299ms srtt=34.478192ms min_rtt=34.472321ms rttvar=12.938864ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1758 app_limited=true congestion_recovery_start_time=None Rate { delivered: 945, delivered_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, first_sent_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27413, is_app_limited: true, interval: 34.472321ms, delivered: 945, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 298, tv_nsec: 878820776 }), send_elapsed: 0ns, ack_elapsed: 34.472321ms, rtt: 34.472321ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 489439, last_update: Instant { tv_sec: 298, tv_nsec: 913601444 }, next_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, 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:13:28.688668095Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 written 1758 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:51250, at: Instant { tv_sec: 298, tv_nsec: 913601444 } })
[2025-04-30T10:13:28.688677553Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:13:28.688684686Z TRACE quiche_server] recv() would block
[2025-04-30T10:13:28.688689215Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 done writing
[2025-04-30T10:13:28.688691850Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:13:28.689158213Z TRACE quiche_server] got 1342 bytes from [::ffff:193.167.0.100]:51250 to [::]:443
[2025-04-30T10:13:28.689165086Z TRACE quiche_server] got packet Short dcid=f398ea2975fb1af662104957ac43f5ebb64c0d64 key_phase=false
[2025-04-30T10:13:28.689170776Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx pkt Short dcid=f398ea2975fb1af662104957ac43f5ebb64c0d64 key_phase=false len=1321 pn=1952395026 
[2025-04-30T10:13:28.689176237Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm PING mtu_probe=None
[2025-04-30T10:13:28.689182579Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm PADDING len=1300
[2025-04-30T10:13:28.689189041Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 processed 1342 bytes
[2025-04-30T10:13:28.689192878Z TRACE quiche_server] recv() would block
[2025-04-30T10:13:28.689197967Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11742 out_len=1350 left=1303
[2025-04-30T10:13:28.689201113Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx pkt Short dcid=d255207b84f70e991cda4b7a77b1e0f3b5 key_phase=false len=12 pn=4 
[2025-04-30T10:13:28.689208247Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 tx frm ACK delay=3 blocks=[1952395025..1952395026] ecn_counts=None
[2025-04-30T10:13:28.689215079Z TRACE quiche::recovery::congestion::recovery] f398ea2975fb1af662104957ac43f5ebb64c0d64 timer=110.571177ms latest_rtt=34.519299ms srtt=34.478192ms min_rtt=34.472321ms rttvar=12.938864ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1758 app_limited=true congestion_recovery_start_time=None Rate { delivered: 945, delivered_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, first_sent_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, end_of_app_limited: 2, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 27413, is_app_limited: true, interval: 34.472321ms, delivered: 945, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 298, tv_nsec: 878820776 }), send_elapsed: 0ns, ack_elapsed: 34.472321ms, rtt: 34.472321ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 489439, last_update: Instant { tv_sec: 298, tv_nsec: 913601444 }, next_time: Instant { tv_sec: 298, tv_nsec: 913601444 }, 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:13:28.689251287Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 written 47 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:51250, at: Instant { tv_sec: 298, tv_nsec: 913601444 } })
[2025-04-30T10:13:28.689259353Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:13:28.689264933Z TRACE quiche_server] recv() would block
[2025-04-30T10:13:28.689269351Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 done writing
[2025-04-30T10:13:28.689272988Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:13:28.720779159Z TRACE quiche_server] got 42 bytes from [::ffff:193.167.0.100]:51250 to [::]:443
[2025-04-30T10:13:28.720807612Z TRACE quiche_server] got packet Short dcid=f398ea2975fb1af662104957ac43f5ebb64c0d64 key_phase=false
[2025-04-30T10:13:28.720824083Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx pkt Short dcid=f398ea2975fb1af662104957ac43f5ebb64c0d64 key_phase=false len=21 pn=1952395027 
[2025-04-30T10:13:28.720839011Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T10:13:28.720879426Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 processed 42 bytes
[2025-04-30T10:13:28.720892220Z TRACE quiche_server] recv() would block
[2025-04-30T10:13:28.720896238Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 done writing
[2025-04-30T10:13:28.720900245Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:13:28.980213400Z TRACE quiche_server] timed out
[2025-04-30T10:13:28.980250359Z TRACE quiche] f398ea2975fb1af662104957ac43f5ebb64c0d64 draining timeout expired
[2025-04-30T10:13:28.980294071Z TRACE quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 done writing
[2025-04-30T10:13:28.980300173Z TRACE quiche_server] Collecting garbage
[2025-04-30T10:13:28.980305763Z INFO  quiche_server] f398ea2975fb1af662104957ac43f5ebb64c0d64 connection collected recv=6 sent=5 lost=0 retrans=0 sent_bytes=2750 recv_bytes=3784 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:51250 validation_state=Validated active=true recv=6 sent=5 lost=0 retrans=0 rtt=34.478192ms min_rtt=Some(34.472321ms) rttvar=12.938864ms cwnd=13500 sent_bytes=2750 recv_bytes=3784 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=27413]