[2025-04-30T18:06:47.696528790Z INFO  quiche_server] listening on [::]:443
[2025-04-30T18:06:47.696551612Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T18:06:47.696558475Z TRACE quiche_server] GSO detected: false
[2025-04-30T18:06:48.515822639Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:36115 to [::]:443
[2025-04-30T18:06:48.515856382Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:06:48.515863966Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:06:48.515958813Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:36115 to [::]:443
[2025-04-30T18:06:48.515963973Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:06:48.515966988Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:06:48.515984581Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:36115 to [::]:443
[2025-04-30T18:06:48.515987647Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:06:48.515990202Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:06:48.516003797Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.715628968Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.715654325Z TRACE quiche_server] got packet Initial version=1 dcid=c99da5c42380e79a scid=14e290d0b960372d token=
[2025-04-30T18:06:48.715686035Z DEBUG quiche_server] New connection: dcid=c99da5c42380e79a scid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca
[2025-04-30T18:06:48.715893823Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Initial version=1 dcid=c99da5c42380e79a scid=14e290d0b960372d token= len=1174 pn=0 
[2025-04-30T18:06:48.715907298Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm CRYPTO off=0 len=305
[2025-04-30T18:06:48.715935691Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T18:06:48.715939668Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T18:06:48.716036048Z TRACE quiche::tls] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca write message lvl=Initial len=123
[2025-04-30T18:06:48.716062268Z TRACE quiche::tls] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca set write secret lvl=Handshake
[2025-04-30T18:06:48.716119174Z TRACE quiche::tls] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca write message lvl=Handshake len=695
[2025-04-30T18:06:48.716122059Z TRACE quiche::tls] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca set write secret lvl=OneRTT
[2025-04-30T18:06:48.716128311Z TRACE quiche::tls] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca set read secret lvl=Handshake
[2025-04-30T18:06:48.716146475Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm PADDING len=845
[2025-04-30T18:06:48.716165290Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 1200 bytes
[2025-04-30T18:06:48.716182773Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.716196158Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Initial version=1 dcid=14e290d0b960372d scid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca len=132 pn=0 
[2025-04-30T18:06:48.716199414Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm ACK delay=39 blocks=[0..0] ecn_counts=None
[2025-04-30T18:06:48.716203802Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm CRYPTO off=0 len=123
[2025-04-30T18:06:48.716213450Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=998.970906ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 8527372 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 8527372 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 8527372 }, next_time: Instant { tv_sec: 466, tv_nsec: 8527372 }, max_datagram_size: 1200, 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:06:48.716242013Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Handshake version=1 dcid=14e290d0b960372d scid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca len=699 pn=1 
[2025-04-30T18:06:48.716245139Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm CRYPTO off=0 len=695
[2025-04-30T18:06:48.716250549Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=998.934227ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=940 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 8527372 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 8527372 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 8527372 }, next_time: Instant { tv_sec: 466, tv_nsec: 8527372 }, max_datagram_size: 1200, 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:06:48.716299300Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59441, at: Instant { tv_sec: 466, tv_nsec: 8527372 } })
[2025-04-30T18:06:48.716308588Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.716315571Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.716321091Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:48.716325059Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.749947787Z TRACE quiche_server] got 96 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.749960330Z TRACE quiche_server] got packet Handshake version=1 dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca scid=14e290d0b960372d
[2025-04-30T18:06:48.749969287Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Handshake version=1 dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca scid=14e290d0b960372d len=59 pn=0 
[2025-04-30T18:06:48.749977231Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm CRYPTO off=0 len=36
[2025-04-30T18:06:48.749989264Z TRACE quiche::tls] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca set read secret lvl=OneRTT
[2025-04-30T18:06:48.750019180Z TRACE quiche::tls] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca write message lvl=OneRTT len=396
[2025-04-30T18:06:48.750027295Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca dropped epoch 1 state
[2025-04-30T18:06:48.750030621Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("P-256") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 120000, stateless_reset_token: None, max_udp_payload_size: 1200, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 16777216, initial_max_stream_data_bidi_remote: 16777216, initial_max_stream_data_uni: 16777216, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(14e290d0b960372d), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T18:06:48.750059535Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca dropped epoch 0 state
[2025-04-30T18:06:48.750062611Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 96 bytes
[2025-04-30T18:06:48.750068862Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.750074072Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12000 out_len=1200 left=1174
[2025-04-30T18:06:48.750082438Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Short dcid=14e290d0b960372d key_phase=false len=681 pn=2 
[2025-04-30T18:06:48.750085223Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[97, 83, f0, bb, fa, 55, d8, 3d, b2, 14, d2, ce, 54, 87, 3c, 60, bd, 9d, 33, b0] reset_token=[bb, b7, 7e, 22, e2, ad, 05, 71, 58, af, f5, 4c, f0, 95, b2, e3]
[2025-04-30T18:06:48.750092747Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[82, 9d, 7f, 7f, 25, ba, b9, 20, ab, ce, a0, 02, ab, 5f, b6, 04, 1f, 9c, 17, 07] reset_token=[cb, 2e, ed, bf, 64, b3, d4, 41, ac, aa, 27, 03, 5c, 07, 21, 0d]
[2025-04-30T18:06:48.750098789Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[1e, 86, 81, 53, 2c, a8, 5f, ff, e2, d3, dd, 00, 4d, 84, d3, bb, d6, cb, 48, 37] reset_token=[85, 94, 28, 75, 66, 00, d5, e0, 94, 5f, 45, 3a, 2b, 10, 51, 94]
[2025-04-30T18:06:48.750104529Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[40, 5a, 47, a3, 5b, b3, 62, 8c, 6e, 94, 54, 01, 33, bb, 21, 50, 8c, a6, 4d, 17] reset_token=[f3, 7d, 39, 8f, fb, 50, 7c, 4a, aa, 0b, c4, aa, 42, 3a, 4b, 4b]
[2025-04-30T18:06:48.750110180Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[bf, 8c, 1e, 69, b1, ca, b4, c6, 1c, 41, 3c, b0, 9c, 6b, dd, ae, 92, 87, d9, 4e] reset_token=[23, b2, 30, 6e, 57, bf, a2, 2a, 0e, e7, 8b, 6e, 43, 84, 5b, 0e]
[2025-04-30T18:06:48.750115941Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[61, 85, 49, ee, e2, 3f, 75, e4, a3, b7, 09, 44, 0e, 2e, 31, 0f, 8d, e0, 07, e4] reset_token=[56, da, 01, 0a, 72, 1d, ad, 66, 74, be, 9a, 52, ca, b9, 22, bc]
[2025-04-30T18:06:48.750121952Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[38, 86, 53, df, ba, fa, e9, b3, e1, d2, 08, e7, 48, cb, 24, f9, fd, bd, e4, e8] reset_token=[46, 7f, be, d9, 06, a3, 11, de, 4c, 1f, 7c, 4a, ed, 93, 74, 3b]
[2025-04-30T18:06:48.750127642Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm HANDSHAKE_DONE
[2025-04-30T18:06:48.750129977Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm CRYPTO off=0 len=396
[2025-04-30T18:06:48.750140697Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=1.023929548s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=707 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 42413322 }, next_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, max_datagram_size: 1200, 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:06:48.750186021Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca written 707 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59441, at: Instant { tv_sec: 466, tv_nsec: 42413322 } })
[2025-04-30T18:06:48.750191522Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.750196331Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.750200649Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:48.750204787Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.750896589Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.750907660Z TRACE quiche_server] got packet Initial version=1 dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca scid=14e290d0b960372d token=
[2025-04-30T18:06:48.750914222Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca dropped invalid packet
[2025-04-30T18:06:48.750917057Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 1200 bytes
[2025-04-30T18:06:48.750920984Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.750923770Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:48.750926064Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.751001074Z TRACE quiche_server] got 63 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.751008518Z TRACE quiche_server] got packet Handshake version=1 dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca scid=14e290d0b960372d
[2025-04-30T18:06:48.751016372Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca dropped invalid packet
[2025-04-30T18:06:48.751020270Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 63 bytes
[2025-04-30T18:06:48.751027824Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.751031711Z TRACE quiche_server] got packet Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false
[2025-04-30T18:06:48.751036260Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false len=41 pn=0 
[2025-04-30T18:06:48.751042000Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T18:06:48.751050366Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 62 bytes
[2025-04-30T18:06:48.751053111Z DEBUG quiche_apps::common] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca response stream 0 is writable with capacity Ok(11293)
[2025-04-30T18:06:48.751059613Z TRACE quiche_apps::common] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca received 17 bytes
[2025-04-30T18:06:48.751062118Z TRACE quiche_apps::common] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca stream 0 has 17 bytes (fin? true)
[2025-04-30T18:06:48.751066196Z INFO  quiche_apps::common] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca got GET request for "/www/gbxfesefck" on stream 0
[2025-04-30T18:06:48.751087135Z INFO  quiche_apps::common] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca sending response of size 5120 on stream 0
[2025-04-30T18:06:48.751093817Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.751099307Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11293 out_len=1200 left=1169
[2025-04-30T18:06:48.751103686Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Short dcid=14e290d0b960372d key_phase=false len=1174 pn=3 
[2025-04-30T18:06:48.751106280Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm ACK delay=7 blocks=[0..0] ecn_counts=None
[2025-04-30T18:06:48.751115217Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm STREAM id=0 off=0 len=1164 fin=false
[2025-04-30T18:06:48.751123042Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=1.023972659s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1907 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 43438317 }, next_time: Instant { tv_sec: 466, tv_nsec: 43438317 }, max_datagram_size: 1200, 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:06:48.751136517Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10093 out_len=1200 left=1174
[2025-04-30T18:06:48.751139933Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Short dcid=14e290d0b960372d key_phase=false len=1174 pn=4 
[2025-04-30T18:06:48.751142388Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm STREAM id=0 off=1164 len=1168 fin=false
[2025-04-30T18:06:48.751147227Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=1.023987517s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=3107 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, end_of_app_limited: 3, last_sent_packet: 4, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 43477310 }, next_time: Instant { tv_sec: 466, tv_nsec: 43477310 }, max_datagram_size: 1200, 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:06:48.751157987Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8893 out_len=1200 left=1174
[2025-04-30T18:06:48.751161113Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Short dcid=14e290d0b960372d key_phase=false len=1174 pn=5 
[2025-04-30T18:06:48.751163477Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm STREAM id=0 off=2332 len=1168 fin=false
[2025-04-30T18:06:48.751167966Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=1.02398875s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=4307 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, end_of_app_limited: 4, last_sent_packet: 5, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 43499271 }, next_time: Instant { tv_sec: 466, tv_nsec: 43499271 }, max_datagram_size: 1200, 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:06:48.751181491Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7693 out_len=1200 left=1174
[2025-04-30T18:06:48.751184527Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Short dcid=14e290d0b960372d key_phase=false len=1174 pn=6 
[2025-04-30T18:06:48.751186891Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm STREAM id=0 off=3500 len=1168 fin=false
[2025-04-30T18:06:48.751191770Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=1.023988468s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=5507 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, end_of_app_limited: 5, last_sent_packet: 6, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 43522784 }, next_time: Instant { tv_sec: 466, tv_nsec: 43522784 }, max_datagram_size: 1200, 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:06:48.751202260Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca pmtud probe status false hs_con=true hs_sent=true cwnd_avail=6493 out_len=1200 left=1174
[2025-04-30T18:06:48.751205135Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Short dcid=14e290d0b960372d key_phase=false len=458 pn=7 
[2025-04-30T18:06:48.751207509Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm STREAM id=0 off=4668 len=452 fin=true
[2025-04-30T18:06:48.751211848Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=1.02398905s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=5991 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, end_of_app_limited: 6, last_sent_packet: 7, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 43543423 }, next_time: Instant { tv_sec: 466, tv_nsec: 43543423 }, max_datagram_size: 1200, 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:06:48.751340879Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca written 5284 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59441, at: Instant { tv_sec: 466, tv_nsec: 43438317 } })
[2025-04-30T18:06:48.751356107Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.751363150Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.751367649Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:48.751371446Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.781577612Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.781609611Z TRACE quiche_server] got packet Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false
[2025-04-30T18:06:48.781627795Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false len=48 pn=1 
[2025-04-30T18:06:48.781644477Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[fc, 71, 74, bc, 16, b9, 5f, 89] reset_token=[11, 48, 2b, c8, 1b, e9, 08, 4d, 03, 6d, 26, 8c, 92, 9c, a7, 27]
[2025-04-30T18:06:48.781682197Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 69 bytes
[2025-04-30T18:06:48.781691113Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.781694550Z TRACE quiche_server] got packet Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false
[2025-04-30T18:06:48.781699259Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false len=48 pn=2 
[2025-04-30T18:06:48.781705360Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[7e, 35, 86, ad, 53, a5, 46, 8e] reset_token=[dd, 98, 6d, d8, ad, d5, ae, 0d, 33, 9c, a2, bb, fd, 32, 40, 01]
[2025-04-30T18:06:48.781712464Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 69 bytes
[2025-04-30T18:06:48.781716361Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.781724075Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca pmtud probe status false hs_con=true hs_sent=true cwnd_avail=6009 out_len=1200 left=1169
[2025-04-30T18:06:48.781729455Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx pkt Short dcid=14e290d0b960372d key_phase=false len=5 pn=8 
[2025-04-30T18:06:48.781732080Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca tx frm ACK delay=2 blocks=[0..2] ecn_counts=None
[2025-04-30T18:06:48.781764922Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca timer=993.435114ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=5991 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 42413322 }, end_of_app_limited: 6, last_sent_packet: 8, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 74060910 }, next_time: Instant { tv_sec: 466, tv_nsec: 74060910 }, max_datagram_size: 1200, 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:06:48.781811458Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca written 31 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59441, at: Instant { tv_sec: 466, tv_nsec: 74060910 } })
[2025-04-30T18:06:48.781817780Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.781826426Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.781829282Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:48.781831586Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.782853555Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.782860909Z TRACE quiche_server] got packet Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false
[2025-04-30T18:06:48.782866339Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false len=25 pn=3 
[2025-04-30T18:06:48.782871869Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm ACK delay=31 blocks=[2..3] ecn_counts=None
[2025-04-30T18:06:48.782875887Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca packet newly acked 2
[2025-04-30T18:06:48.782880155Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca packet newly acked 3
[2025-04-30T18:06:48.782895062Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 46 bytes
[2025-04-30T18:06:48.782899100Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.782901735Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:48.782903999Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.784649440Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.784656092Z TRACE quiche_server] got packet Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false
[2025-04-30T18:06:48.784680838Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false len=25 pn=4 
[2025-04-30T18:06:48.784686008Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm ACK delay=13 blocks=[2..5] ecn_counts=None
[2025-04-30T18:06:48.784689104Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca packet newly acked 4
[2025-04-30T18:06:48.784691659Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca packet newly acked 5
[2025-04-30T18:06:48.784700735Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 46 bytes
[2025-04-30T18:06:48.784704663Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.784707298Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:48.784709532Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:48.786108806Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:48.786115428Z TRACE quiche_server] got packet Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false
[2025-04-30T18:06:48.786119877Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false len=25 pn=5 
[2025-04-30T18:06:48.786124405Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm ACK delay=19 blocks=[2..7] ecn_counts=None
[2025-04-30T18:06:48.786127371Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca packet newly acked 6
[2025-04-30T18:06:48.786129645Z TRACE quiche::recovery::congestion::recovery] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca packet newly acked 7
[2025-04-30T18:06:48.786140726Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 46 bytes
[2025-04-30T18:06:48.786144412Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:48.786146987Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:48.786149161Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.162307324Z TRACE quiche_server] got 45 bytes from [::ffff:193.167.0.100]:59441 to [::]:443
[2025-04-30T18:06:49.162330758Z TRACE quiche_server] got packet Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false
[2025-04-30T18:06:49.162353791Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx pkt Short dcid=c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca key_phase=false len=24 pn=13 
[2025-04-30T18:06:49.162365583Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T18:06:49.162383286Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca processed 45 bytes
[2025-04-30T18:06:49.162389668Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.162392333Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:49.162394978Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.379646183Z TRACE quiche_server] timed out
[2025-04-30T18:06:49.379705694Z TRACE quiche] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca draining timeout expired
[2025-04-30T18:06:49.379753543Z TRACE quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca done writing
[2025-04-30T18:06:49.379757020Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.379760456Z INFO  quiche_server] c544aecbdfba31c9a53d8d0a4d6bb75ed7600eca connection collected recv=9 sent=9 lost=0 retrans=0 sent_bytes=6962 recv_bytes=1679 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:59441 validation_state=Validated active=true recv=9 sent=9 lost=0 retrans=0 rtt=32.324581ms min_rtt=Some(31.769576ms) rttvar=9.942104ms cwnd=12000 sent_bytes=6962 recv_bytes=1679 lost_bytes=0 stream_retrans_bytes=0 pmtu=1200 delivery_rate=182682]
[2025-04-30T18:06:49.522870839Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.522894864Z TRACE quiche_server] got packet Initial version=1 dcid=3251f0533572d3b8 scid=bb69d1dfd80a0919 token=
[2025-04-30T18:06:49.522903530Z DEBUG quiche_server] New connection: dcid=3251f0533572d3b8 scid=e941f5c9770974987f7c0264aabd3789b7ea48df
[2025-04-30T18:06:49.523051436Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Initial version=1 dcid=3251f0533572d3b8 scid=bb69d1dfd80a0919 token= len=1174 pn=0 
[2025-04-30T18:06:49.523064120Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm CRYPTO off=0 len=305
[2025-04-30T18:06:49.523092703Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T18:06:49.523097262Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T18:06:49.523200995Z TRACE quiche::tls] e941f5c9770974987f7c0264aabd3789b7ea48df write message lvl=Initial len=123
[2025-04-30T18:06:49.523216965Z TRACE quiche::tls] e941f5c9770974987f7c0264aabd3789b7ea48df set write secret lvl=Handshake
[2025-04-30T18:06:49.523279141Z TRACE quiche::tls] e941f5c9770974987f7c0264aabd3789b7ea48df write message lvl=Handshake len=695
[2025-04-30T18:06:49.523282237Z TRACE quiche::tls] e941f5c9770974987f7c0264aabd3789b7ea48df set write secret lvl=OneRTT
[2025-04-30T18:06:49.523288819Z TRACE quiche::tls] e941f5c9770974987f7c0264aabd3789b7ea48df set read secret lvl=Handshake
[2025-04-30T18:06:49.523303667Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm PADDING len=845
[2025-04-30T18:06:49.523322963Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 1200 bytes
[2025-04-30T18:06:49.523340075Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.523353360Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Initial version=1 dcid=bb69d1dfd80a0919 scid=e941f5c9770974987f7c0264aabd3789b7ea48df len=132 pn=0 
[2025-04-30T18:06:49.523356836Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm ACK delay=39 blocks=[0..0] ecn_counts=None
[2025-04-30T18:06:49.523361896Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm CRYPTO off=0 len=123
[2025-04-30T18:06:49.523371594Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=998.970104ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 815684805 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 815684805 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 815684805 }, next_time: Instant { tv_sec: 466, tv_nsec: 815684805 }, max_datagram_size: 1200, 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:06:49.523400368Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Handshake version=1 dcid=bb69d1dfd80a0919 scid=e941f5c9770974987f7c0264aabd3789b7ea48df len=699 pn=1 
[2025-04-30T18:06:49.523403293Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm CRYPTO off=0 len=695
[2025-04-30T18:06:49.523409074Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=998.933125ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=940 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 815684805 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 815684805 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 815684805 }, next_time: Instant { tv_sec: 466, tv_nsec: 815684805 }, max_datagram_size: 1200, 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:06:49.523458837Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:50648, at: Instant { tv_sec: 466, tv_nsec: 815684805 } })
[2025-04-30T18:06:49.523465269Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.523470008Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.523473825Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.523476109Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.556530063Z TRACE quiche_server] got 96 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.556552114Z TRACE quiche_server] got packet Handshake version=1 dcid=e941f5c9770974987f7c0264aabd3789b7ea48df scid=bb69d1dfd80a0919
[2025-04-30T18:06:49.556564668Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Handshake version=1 dcid=e941f5c9770974987f7c0264aabd3789b7ea48df scid=bb69d1dfd80a0919 len=59 pn=0 
[2025-04-30T18:06:49.556576710Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm CRYPTO off=0 len=36
[2025-04-30T18:06:49.556595055Z TRACE quiche::tls] e941f5c9770974987f7c0264aabd3789b7ea48df set read secret lvl=OneRTT
[2025-04-30T18:06:49.556626714Z TRACE quiche::tls] e941f5c9770974987f7c0264aabd3789b7ea48df write message lvl=OneRTT len=396
[2025-04-30T18:06:49.556635891Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df dropped epoch 1 state
[2025-04-30T18:06:49.556640119Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("P-256") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 120000, stateless_reset_token: None, max_udp_payload_size: 1200, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 16777216, initial_max_stream_data_bidi_remote: 16777216, initial_max_stream_data_uni: 16777216, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(bb69d1dfd80a0919), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T18:06:49.556706513Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df dropped epoch 0 state
[2025-04-30T18:06:49.556713296Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 96 bytes
[2025-04-30T18:06:49.556720098Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.556726881Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12000 out_len=1200 left=1174
[2025-04-30T18:06:49.556736929Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=681 pn=2 
[2025-04-30T18:06:49.556739995Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[32, 84, f0, 0b, ef, c1, de, 91, 38, 3e, 76, 74, 64, 36, 26, 3d, 9a, d9, 67, 04] reset_token=[3e, 37, 65, f4, 36, 16, 04, db, e6, ab, f6, 49, 06, 88, e4, 5b]
[2025-04-30T18:06:49.556748611Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[8e, 08, 94, 47, f4, 75, 2b, 56, d9, 2d, 66, 48, ec, 15, 62, b9, 57, 61, 1a, 33] reset_token=[27, 80, 51, a9, 06, f0, 12, 5e, 7a, fb, f0, 94, df, 51, 13, 71]
[2025-04-30T18:06:49.556755715Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[45, 8b, 32, ee, 8f, 6f, 0e, 02, 12, 4b, 1d, db, 95, e0, b5, 21, 60, f4, 4b, 92] reset_token=[b0, cb, 2f, e3, a2, 58, 44, c1, 03, a4, a6, a9, 4d, 69, 03, d2]
[2025-04-30T18:06:49.556762427Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[21, 0b, 5e, d4, 5c, 25, 8e, df, 1b, db, 5d, 6e, 9b, 57, 54, 4f, 95, b6, 84, e4] reset_token=[79, 44, 4c, d0, 20, d6, 34, a7, d7, 27, 9a, 22, 99, b5, c7, aa]
[2025-04-30T18:06:49.556769070Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[d5, 52, 23, cd, 48, ab, e0, eb, 0c, 11, 4c, f1, e8, d7, 99, 6c, 3f, fd, 51, f5] reset_token=[84, 32, 88, 71, 7f, ce, 5a, cb, bb, b3, cf, 25, 61, f4, 1c, e3]
[2025-04-30T18:06:49.556775441Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[5e, e0, f8, 4f, 82, 23, fc, 48, f2, fb, 2e, 88, 21, be, 71, 6a, 50, d1, 7b, 31] reset_token=[36, d4, 1b, dc, e0, 9d, a0, da, ac, 9d, fe, 85, 23, 13, 55, be]
[2025-04-30T18:06:49.556781804Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[b2, 2b, 5d, f9, 7a, fa, d1, 9f, 70, d8, 63, c9, 00, de, d3, 2d, dc, 7b, bb, ba] reset_token=[d4, 55, 22, 6e, 01, ef, b1, 0d, d4, a4, dc, 1c, e1, ca, 1b, fc]
[2025-04-30T18:06:49.556788265Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm HANDSHAKE_DONE
[2025-04-30T18:06:49.556790760Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm CRYPTO off=0 len=396
[2025-04-30T18:06:49.556803814Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.023918077s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=707 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 849064848 }, next_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, max_datagram_size: 1200, 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:06:49.556861833Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df written 707 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:50648, at: Instant { tv_sec: 466, tv_nsec: 849064848 } })
[2025-04-30T18:06:49.556867894Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.556872282Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.556875198Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.556877392Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.557471121Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.557475469Z TRACE quiche_server] got packet Initial version=1 dcid=e941f5c9770974987f7c0264aabd3789b7ea48df scid=bb69d1dfd80a0919 token=
[2025-04-30T18:06:49.557481911Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df dropped invalid packet
[2025-04-30T18:06:49.557484556Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 1200 bytes
[2025-04-30T18:06:49.557489175Z TRACE quiche_server] got 63 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.557492010Z TRACE quiche_server] got packet Handshake version=1 dcid=e941f5c9770974987f7c0264aabd3789b7ea48df scid=bb69d1dfd80a0919
[2025-04-30T18:06:49.557496418Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df dropped invalid packet
[2025-04-30T18:06:49.557498703Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 63 bytes
[2025-04-30T18:06:49.557501698Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.557504113Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.557507308Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.557611193Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.557615190Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.557619698Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=41 pn=0 
[2025-04-30T18:06:49.557625529Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T18:06:49.557633684Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 62 bytes
[2025-04-30T18:06:49.557636440Z DEBUG quiche_apps::common] e941f5c9770974987f7c0264aabd3789b7ea48df response stream 0 is writable with capacity Ok(11293)
[2025-04-30T18:06:49.557642701Z TRACE quiche_apps::common] e941f5c9770974987f7c0264aabd3789b7ea48df received 17 bytes
[2025-04-30T18:06:49.557645176Z TRACE quiche_apps::common] e941f5c9770974987f7c0264aabd3789b7ea48df stream 0 has 17 bytes (fin? true)
[2025-04-30T18:06:49.557649845Z INFO  quiche_apps::common] e941f5c9770974987f7c0264aabd3789b7ea48df got GET request for "/www/xbodbmjibg" on stream 0
[2025-04-30T18:06:49.557701662Z INFO  quiche_apps::common] e941f5c9770974987f7c0264aabd3789b7ea48df sending response of size 10240 on stream 0
[2025-04-30T18:06:49.557713534Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.557719425Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11293 out_len=1200 left=1169
[2025-04-30T18:06:49.557728742Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=1174 pn=3 
[2025-04-30T18:06:49.557731437Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm ACK delay=12 blocks=[0..0] ecn_counts=None
[2025-04-30T18:06:49.557735695Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=0 len=1164 fin=false
[2025-04-30T18:06:49.557743730Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.023971747s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1907 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850058173 }, next_time: Instant { tv_sec: 466, tv_nsec: 850058173 }, max_datagram_size: 1200, 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:06:49.557757446Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10093 out_len=1200 left=1174
[2025-04-30T18:06:49.557760612Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=1174 pn=4 
[2025-04-30T18:06:49.557763036Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=1164 len=1168 fin=false
[2025-04-30T18:06:49.557767855Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.023987757s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=3107 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 4, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850098148 }, next_time: Instant { tv_sec: 466, tv_nsec: 850098148 }, max_datagram_size: 1200, 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:06:49.557778455Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8893 out_len=1200 left=1174
[2025-04-30T18:06:49.557781521Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=1174 pn=5 
[2025-04-30T18:06:49.557783865Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=2332 len=1168 fin=false
[2025-04-30T18:06:49.557788063Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.023989159s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=4307 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 5, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850119748 }, next_time: Instant { tv_sec: 466, tv_nsec: 850119748 }, max_datagram_size: 1200, 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:06:49.557801418Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7693 out_len=1200 left=1174
[2025-04-30T18:06:49.557804494Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=1174 pn=6 
[2025-04-30T18:06:49.557806878Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=3500 len=1168 fin=false
[2025-04-30T18:06:49.557811627Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.023988578s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=5507 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 6, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850142741 }, next_time: Instant { tv_sec: 466, tv_nsec: 850142741 }, max_datagram_size: 1200, 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:06:49.557822056Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=6493 out_len=1200 left=1174
[2025-04-30T18:06:49.557824972Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=1174 pn=7 
[2025-04-30T18:06:49.557827286Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=4668 len=1168 fin=false
[2025-04-30T18:06:49.557831374Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.023989309s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=6707 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 7, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850163219 }, next_time: Instant { tv_sec: 466, tv_nsec: 850163219 }, max_datagram_size: 1200, 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:06:49.557844178Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=5293 out_len=1200 left=1174
[2025-04-30T18:06:49.557847093Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=1174 pn=8 
[2025-04-30T18:06:49.557849427Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=5836 len=1168 fin=false
[2025-04-30T18:06:49.557853675Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.02398927s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=7907 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 8, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850185461 }, next_time: Instant { tv_sec: 466, tv_nsec: 850185461 }, max_datagram_size: 1200, 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:06:49.557863915Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4093 out_len=1200 left=1174
[2025-04-30T18:06:49.557866810Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=1174 pn=9 
[2025-04-30T18:06:49.557869124Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=7004 len=1168 fin=false
[2025-04-30T18:06:49.557873222Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.02398956s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=9107 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 9, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850205318 }, next_time: Instant { tv_sec: 466, tv_nsec: 850205318 }, max_datagram_size: 1200, 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:06:49.557883321Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=2893 out_len=1200 left=1174
[2025-04-30T18:06:49.557886186Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=1174 pn=10 
[2025-04-30T18:06:49.557888571Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=8172 len=1168 fin=false
[2025-04-30T18:06:49.557914960Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.023967098s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=10307 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 10, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850224714 }, next_time: Instant { tv_sec: 466, tv_nsec: 850224714 }, max_datagram_size: 1200, 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:06:49.557926121Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=1693 out_len=1200 left=1174
[2025-04-30T18:06:49.557929127Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=906 pn=11 
[2025-04-30T18:06:49.557931511Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm STREAM id=0 off=9340 len=900 fin=true
[2025-04-30T18:06:49.557936180Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=1.023988539s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=11239 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 11, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 850267264 }, next_time: Instant { tv_sec: 466, tv_nsec: 850267264 }, max_datagram_size: 1200, 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:06:49.558052888Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df written 10532 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:50648, at: Instant { tv_sec: 466, tv_nsec: 850058173 } })
[2025-04-30T18:06:49.558058188Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.558062025Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.558064770Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.558066934Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.588279644Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.588296265Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.588307246Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=48 pn=4 
[2025-04-30T18:06:49.588318276Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 4b, 9c, 16, a6, 64, 73, 65] reset_token=[e8, 91, f3, 02, 9c, c1, ef, b1, 55, fe, 1d, 69, c5, ce, f4, 8a]
[2025-04-30T18:06:49.588338654Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 69 bytes
[2025-04-30T18:06:49.588348082Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.588353152Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.588360475Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=48 pn=5 
[2025-04-30T18:06:49.588369031Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[0a, 54, c6, 6f, 13, 74, f0, f1] reset_token=[49, 6a, e1, f4, 25, 19, 7a, 6f, 1d, 85, 41, 27, 48, 57, af, cb]
[2025-04-30T18:06:49.588379010Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 69 bytes
[2025-04-30T18:06:49.588383157Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.588390161Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df pmtud probe status false hs_con=true hs_sent=true cwnd_avail=761 out_len=1200 left=728
[2025-04-30T18:06:49.588395080Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx pkt Short dcid=bb69d1dfd80a0919 key_phase=false len=7 pn=12 
[2025-04-30T18:06:49.588397575Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df tx frm ACK delay=3 blocks=[0..0, 4..5] ecn_counts=None
[2025-04-30T18:06:49.588406431Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df timer=993.517506ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=11239 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, first_sent_time: Instant { tv_sec: 466, tv_nsec: 849064848 }, end_of_app_limited: 2, last_sent_packet: 12, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 466, tv_nsec: 880727757 }, next_time: Instant { tv_sec: 466, tv_nsec: 880727757 }, max_datagram_size: 1200, 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:06:49.588452507Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df written 33 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:50648, at: Instant { tv_sec: 466, tv_nsec: 880727757 } })
[2025-04-30T18:06:49.588458538Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.588463277Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.588466203Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.588468477Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.589560487Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.589567820Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.589573030Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=25 pn=6 
[2025-04-30T18:06:49.589578581Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm ACK delay=29 blocks=[2..3] ecn_counts=None
[2025-04-30T18:06:49.589582578Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 2
[2025-04-30T18:06:49.589586645Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 3
[2025-04-30T18:06:49.589605901Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 46 bytes
[2025-04-30T18:06:49.589610049Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.589612714Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.589614908Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.591369566Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.591376368Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.591381217Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=25 pn=7 
[2025-04-30T18:06:49.591386037Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm ACK delay=13 blocks=[2..5] ecn_counts=None
[2025-04-30T18:06:49.591389012Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 4
[2025-04-30T18:06:49.591391447Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 5
[2025-04-30T18:06:49.591399802Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 46 bytes
[2025-04-30T18:06:49.591403650Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.591406154Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.591408358Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.593336369Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.593342872Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.593347410Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=25 pn=8 
[2025-04-30T18:06:49.593351999Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm ACK delay=13 blocks=[2..7] ecn_counts=None
[2025-04-30T18:06:49.593354864Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 6
[2025-04-30T18:06:49.593357288Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 7
[2025-04-30T18:06:49.593365434Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 46 bytes
[2025-04-30T18:06:49.593369091Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.593371575Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.593373729Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.595302682Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.595309074Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.595313843Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=25 pn=9 
[2025-04-30T18:06:49.595318422Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm ACK delay=12 blocks=[2..9] ecn_counts=None
[2025-04-30T18:06:49.595321297Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 8
[2025-04-30T18:06:49.595323672Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 9
[2025-04-30T18:06:49.595331246Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 46 bytes
[2025-04-30T18:06:49.595334792Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.595337327Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.595339501Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.597117783Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.597130156Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.597135175Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=25 pn=10 
[2025-04-30T18:06:49.597139924Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm ACK delay=17 blocks=[2..11] ecn_counts=None
[2025-04-30T18:06:49.597142970Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 10
[2025-04-30T18:06:49.597145374Z TRACE quiche::recovery::congestion::recovery] e941f5c9770974987f7c0264aabd3789b7ea48df packet newly acked 11
[2025-04-30T18:06:49.597156305Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 46 bytes
[2025-04-30T18:06:49.597160242Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.597162807Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.597164951Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:49.922113427Z TRACE quiche_server] got 45 bytes from [::ffff:193.167.0.100]:50648 to [::]:443
[2025-04-30T18:06:49.922137713Z TRACE quiche_server] got packet Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false
[2025-04-30T18:06:49.922151579Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx pkt Short dcid=e941f5c9770974987f7c0264aabd3789b7ea48df key_phase=false len=24 pn=11 
[2025-04-30T18:06:49.922163551Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T18:06:49.922181014Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df processed 45 bytes
[2025-04-30T18:06:49.922187616Z TRACE quiche_server] recv() would block
[2025-04-30T18:06:49.922190351Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:49.922193447Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:50.121415920Z TRACE quiche_server] timed out
[2025-04-30T18:06:50.121448110Z TRACE quiche] e941f5c9770974987f7c0264aabd3789b7ea48df draining timeout expired
[2025-04-30T18:06:50.121496490Z TRACE quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df done writing
[2025-04-30T18:06:50.121499987Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:06:50.121503273Z INFO  quiche_server] e941f5c9770974987f7c0264aabd3789b7ea48df connection collected recv=11 sent=13 lost=0 retrans=0 sent_bytes=12212 recv_bytes=1771 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:50648 validation_state=Validated active=true recv=11 sent=13 lost=0 retrans=0 rtt=33.838599ms min_rtt=Some(31.856371ms) rttvar=8.094785ms cwnd=12000 sent_bytes=12212 recv_bytes=1771 lost_bytes=0 stream_retrans_bytes=0 pmtu=1200 delivery_rate=342134]