[2025-04-30T18:24:35.804963433Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T18:24:35.805291066Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:49231 with scid 1ea90276a44bfddaa590c69327bab428ab7ee1ea
[2025-04-30T18:24:35.805333416Z TRACE quiche::tls] 1ea90276a44bfddaa590c69327bab428ab7ee1ea write message lvl=Initial len=266
[2025-04-30T18:24:35.805349706Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Initial version=1 dcid=b904b5471867ac90326ffe1cf7ca5b5d scid=1ea90276a44bfddaa590c69327bab428ab7ee1ea len=270 pn=0 
[2025-04-30T18:24:35.805352992Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm CRYPTO off=0 len=266
[2025-04-30T18:24:35.805367229Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=998.932223ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, 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: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 227, tv_nsec: 955324145 }, next_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:24:35.805464791Z TRACE quiche_apps::client] written 1200
[2025-04-30T18:24:36.805491229Z TRACE quiche_apps::client] timed out
[2025-04-30T18:24:36.805520464Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea loss detection timeout expired
[2025-04-30T18:24:36.805528268Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=997.770713ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, 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: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 227, tv_nsec: 955324145 }, next_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:24:36.805550400Z TRACE quiche_apps::client] done reading
[2025-04-30T18:24:36.805587950Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Initial version=1 dcid=b904b5471867ac90326ffe1cf7ca5b5d scid=1ea90276a44bfddaa590c69327bab428ab7ee1ea len=270 pn=1 
[2025-04-30T18:24:36.805592148Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm CRYPTO off=0 len=266
[2025-04-30T18:24:36.805609590Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=1.997956057s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=666 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, 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: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 228, tv_nsec: 955590030 }, next_time: Instant { tv_sec: 228, tv_nsec: 955590030 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:24:36.805706461Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:49231 to 193.167.100.100:443
[2025-04-30T18:24:36.805716059Z TRACE quiche_apps::client] 0.0.0.0:49231 -> 193.167.100.100:443: done writing
[2025-04-30T18:24:37.314002795Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:49231
[2025-04-30T18:24:37.314046917Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx pkt Initial version=1 dcid=1ea90276a44bfddaa590c69327bab428ab7ee1ea scid=861f46a8c100123b5fd85159e042a0d535e63fee token= len=116 pn=0 
[2025-04-30T18:24:37.314065442Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm ACK delay=47 blocks=[0..0] ecn_counts=None
[2025-04-30T18:24:37.314073126Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea packet newly acked 0
[2025-04-30T18:24:37.314080310Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm CRYPTO off=0 len=90
[2025-04-30T18:24:37.314207628Z TRACE quiche::tls] 1ea90276a44bfddaa590c69327bab428ab7ee1ea set write secret lvl=Handshake
[2025-04-30T18:24:37.314220512Z TRACE quiche::tls] 1ea90276a44bfddaa590c69327bab428ab7ee1ea set read secret lvl=Handshake
[2025-04-30T18:24:37.314262621Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx pkt Handshake version=1 dcid=1ea90276a44bfddaa590c69327bab428ab7ee1ea scid=861f46a8c100123b5fd85159e042a0d535e63fee len=719 pn=1 
[2025-04-30T18:24:37.314270776Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm CRYPTO off=0 len=698
[2025-04-30T18:24:37.314526244Z TRACE quiche::tls] 1ea90276a44bfddaa590c69327bab428ab7ee1ea write message lvl=Handshake len=36
[2025-04-30T18:24:37.314531644Z TRACE quiche::tls] 1ea90276a44bfddaa590c69327bab428ab7ee1ea set write secret lvl=OneRTT
[2025-04-30T18:24:37.314539830Z TRACE quiche::tls] 1ea90276a44bfddaa590c69327bab428ab7ee1ea set read secret lvl=OneRTT
[2025-04-30T18:24:37.314559106Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(b904b5471867ac90326ffe1cf7ca5b5d), max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1350, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(861f46a8c100123b5fd85159e042a0d535e63fee), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T18:24:37.314584774Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx pkt Short dcid=0000000000000000000000000000000000000000 key_phase=false len=245 pn=2812512708 
[2025-04-30T18:24:37.314591085Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea dropped invalid packet
[2025-04-30T18:24:37.314594662Z TRACE quiche_apps::client] 0.0.0.0:49231: processed 1200 bytes
[2025-04-30T18:24:37.314606695Z TRACE quiche_apps::client] 0.0.0.0:49231: recv() would block
[2025-04-30T18:24:37.314610231Z TRACE quiche_apps::client] done reading
[2025-04-30T18:24:37.314632162Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm SETTINGS stream=2 len=18
[2025-04-30T18:24:37.314647501Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea open GREASE stream 14
[2025-04-30T18:24:37.314657570Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm GREASE stream=0 len=0
[2025-04-30T18:24:37.314664703Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm GREASE stream=0 len=18
[2025-04-30T18:24:37.314673560Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm HEADERS stream=0 len=30 fin=true
[2025-04-30T18:24:37.314681064Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /nbbmmnbkke", "user-agent: quiche"]
[2025-04-30T18:24:37.314783205Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Initial version=1 dcid=861f46a8c100123b5fd85159e042a0d535e63fee scid=1ea90276a44bfddaa590c69327bab428ab7ee1ea len=6 pn=2 
[2025-04-30T18:24:37.314787062Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm ACK delay=92 blocks=[0..0] ecn_counts=None
[2025-04-30T18:24:37.314796510Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=4.016986578s latest_rtt=1.50873918s srtt=1.50873918s min_rtt=1.50873918s rttvar=754.36959ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 464063325 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.50873918s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 227, tv_nsec: 955324145 }), send_elapsed: 0ns, ack_elapsed: 1.50873918s, rtt: 1.50873918s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11184, last_update: Instant { tv_sec: 229, tv_nsec: 464580462 }, next_time: Instant { tv_sec: 229, tv_nsec: 464580502 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:24:37.314818320Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Handshake version=1 dcid=861f46a8c100123b5fd85159e042a0d535e63fee scid=1ea90276a44bfddaa590c69327bab428ab7ee1ea len=46 pn=3 
[2025-04-30T18:24:37.314821166Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm ACK delay=69 blocks=[1..1] ecn_counts=None
[2025-04-30T18:24:37.314823901Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm CRYPTO off=0 len=36
[2025-04-30T18:24:37.314829872Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=4.016953496s latest_rtt=1.50873918s srtt=1.50873918s min_rtt=1.50873918s rttvar=754.36959ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=445 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 464063325 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.50873918s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 227, tv_nsec: 955324145 }), send_elapsed: 0ns, ack_elapsed: 1.50873918s, rtt: 1.50873918s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11184, last_update: Instant { tv_sec: 229, tv_nsec: 464797598 }, next_time: Instant { tv_sec: 229, tv_nsec: 464580502 }, max_datagram_size: 1350, last_packet_size: None, iv: 10.014306ms, 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:24:37.314847194Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea dropped epoch 0 state
[2025-04-30T18:24:37.314852054Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13388 out_len=1165 left=1127
[2025-04-30T18:24:37.314863615Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Short dcid=861f46a8c100123b5fd85159e042a0d535e63fee key_phase=false len=1127 pn=4 
[2025-04-30T18:24:37.314866270Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[be, 8f, d8, c5, 74, f1, ea, 5f, 42, 2e, 31, dd, 31, ca, 79, 0e, ac, 3b, a5, b4] reset_token=[c6, 7e, 02, dd, c6, a2, 8d, 91, 23, ff, f4, ca, fb, 7b, 6f, 05]
[2025-04-30T18:24:37.314873353Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[0a, d6, 43, d4, 73, 14, 3b, ff, 7d, 7c, e8, db, 89, 23, 55, de, dc, 70, a7, a5] reset_token=[ed, 88, a4, 31, fe, 50, 62, d2, e3, 74, 6e, f4, 2c, 79, 61, 3d]
[2025-04-30T18:24:37.314878904Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[c0, 70, c1, d0, 7f, ef, 9e, 88, 49, 5d, 76, fd, 6b, 10, bd, 45, c8, 49, 5a, 11] reset_token=[5e, 6a, 28, da, f9, b6, 1f, 8a, e3, 3c, 83, 7e, 7a, 90, 4e, ba]
[2025-04-30T18:24:37.314884083Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[b3, dc, 4e, 7c, 12, 5f, 85, 0c, 0f, 21, 3e, 52, 30, ba, e0, b9, 8f, 72, dd, 95] reset_token=[a2, 46, 56, 03, bc, 8c, 00, e5, 6e, 35, dc, 72, cc, 85, 7f, 3c]
[2025-04-30T18:24:37.314889433Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[1f, 81, 70, da, ef, 09, 46, 88, 23, e9, b4, 50, 1d, b1, 17, be, ef, 1d, 14, 9e] reset_token=[8d, f1, 7a, 98, 3a, 41, e8, 0b, d3, 99, 69, 1b, dd, ed, 63, 61]
[2025-04-30T18:24:37.314894673Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[c3, 61, 5b, c9, d6, 06, 3c, 7d, e8, 7e, 5c, 40, 46, bf, d5, 06, 9a, e7, 41, ef] reset_token=[6e, 5e, 81, d8, 16, 04, 23, db, bd, 5a, a1, fa, 4e, c4, aa, 60]
[2025-04-30T18:24:37.314899923Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[76, 7e, 96, 93, 19, 35, 9b, 65, 89, 23, 79, 50, a2, 38, cd, 0a, 1c, bb, 6a, 71] reset_token=[df, 04, 14, d8, 52, 40, 2d, e2, f3, 91, 27, f2, db, b2, e6, 43]
[2025-04-30T18:24:37.314905213Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T18:24:37.314907748Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm PADDING len=823
[2025-04-30T18:24:37.314932284Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=4.526058563s latest_rtt=1.50873918s srtt=1.50873918s min_rtt=1.50873918s rttvar=754.36959ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1277 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 464063325 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.50873918s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 227, tv_nsec: 955324145 }), send_elapsed: 0ns, ack_elapsed: 1.50873918s, rtt: 1.50873918s } } pacer=Pacer { enabled: true, capacity: 13500, used: 1165, rate: 11184, last_update: Instant { tv_sec: 229, tv_nsec: 464797598 }, next_time: Instant { tv_sec: 229, tv_nsec: 474811904 }, max_datagram_size: 1350, last_packet_size: Some(1165), 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:24:37.315009508Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:49231 to 193.167.100.100:443
[2025-04-30T18:24:37.315024416Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12223 out_len=1350 left=1312
[2025-04-30T18:24:37.315029355Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Short dcid=861f46a8c100123b5fd85159e042a0d535e63fee key_phase=false len=6 pn=5 
[2025-04-30T18:24:37.315031990Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T18:24:37.315039434Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=4.525951462s latest_rtt=1.50873918s srtt=1.50873918s min_rtt=1.50873918s rttvar=754.36959ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 464063325 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.50873918s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 227, tv_nsec: 955324145 }), send_elapsed: 0ns, ack_elapsed: 1.50873918s, rtt: 1.50873918s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11184, last_update: Instant { tv_sec: 229, tv_nsec: 465043288 }, next_time: Instant { tv_sec: 229, tv_nsec: 474811904 }, max_datagram_size: 1350, last_packet_size: None, iv: 108.100858ms, 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:24:37.315091942Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:49231 to 193.167.100.100:443
[2025-04-30T18:24:37.315103604Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12179 out_len=1350 left=1312
[2025-04-30T18:24:37.315109725Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Short dcid=861f46a8c100123b5fd85159e042a0d535e63fee key_phase=false len=6 pn=6 
[2025-04-30T18:24:37.315113032Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T18:24:37.315120876Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=4.525869759s latest_rtt=1.50873918s srtt=1.50873918s min_rtt=1.50873918s rttvar=754.36959ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1365 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 464063325 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.50873918s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 227, tv_nsec: 955324145 }), send_elapsed: 0ns, ack_elapsed: 1.50873918s, rtt: 1.50873918s } } pacer=Pacer { enabled: true, capacity: 13500, used: 44, rate: 11184, last_update: Instant { tv_sec: 229, tv_nsec: 465043288 }, next_time: Instant { tv_sec: 229, tv_nsec: 582912762 }, max_datagram_size: 1350, last_packet_size: Some(44), 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:24:37.315173364Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:49231 to 193.167.100.100:443
[2025-04-30T18:24:37.315189224Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12135 out_len=1350 left=1312
[2025-04-30T18:24:37.315199253Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Short dcid=861f46a8c100123b5fd85159e042a0d535e63fee key_phase=false len=73 pn=7 
[2025-04-30T18:24:37.315204082Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T18:24:37.315217637Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=4.525772738s latest_rtt=1.50873918s srtt=1.50873918s min_rtt=1.50873918s rttvar=754.36959ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1476 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 464063325 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.50873918s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 227, tv_nsec: 955324145 }), send_elapsed: 0ns, ack_elapsed: 1.50873918s, rtt: 1.50873918s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11184, last_update: Instant { tv_sec: 229, tv_nsec: 465204519 }, next_time: Instant { tv_sec: 229, tv_nsec: 582912762 }, max_datagram_size: 1350, last_packet_size: None, iv: 13.859084ms, 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:24:37.315279483Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:49231 to 193.167.100.100:443
[2025-04-30T18:24:37.315294281Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12024 out_len=1350 left=1312
[2025-04-30T18:24:37.315302827Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Short dcid=861f46a8c100123b5fd85159e042a0d535e63fee key_phase=false len=31 pn=8 
[2025-04-30T18:24:37.315307245Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T18:24:37.315319007Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=4.525671408s latest_rtt=1.50873918s srtt=1.50873918s min_rtt=1.50873918s rttvar=754.36959ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1545 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 464063325 }, first_sent_time: Instant { tv_sec: 227, tv_nsec: 955324145 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.50873918s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 227, tv_nsec: 955324145 }), send_elapsed: 0ns, ack_elapsed: 1.50873918s, rtt: 1.50873918s } } pacer=Pacer { enabled: true, capacity: 13500, used: 69, rate: 11184, last_update: Instant { tv_sec: 229, tv_nsec: 465204519 }, next_time: Instant { tv_sec: 229, tv_nsec: 596771846 }, max_datagram_size: 1350, last_packet_size: Some(69), 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:24:37.315365033Z TRACE quiche_apps::client] written 69 bytes from 0.0.0.0:49231 to 193.167.100.100:443
[2025-04-30T18:24:37.315369892Z TRACE quiche_apps::client] 0.0.0.0:49231 -> 193.167.100.100:443: done writing
[2025-04-30T18:24:38.308533792Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:49231
[2025-04-30T18:24:38.308569579Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea dropped invalid packet
[2025-04-30T18:24:38.308574368Z TRACE quiche_apps::client] 0.0.0.0:49231: processed 1200 bytes
[2025-04-30T18:24:38.308579918Z TRACE quiche_apps::client] 0.0.0.0:49231: recv() would block
[2025-04-30T18:24:38.308583255Z TRACE quiche_apps::client] done reading
[2025-04-30T18:24:38.308593223Z TRACE quiche_apps::client] 0.0.0.0:49231 -> 193.167.100.100:443: done writing
[2025-04-30T18:24:38.313685889Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:49231
[2025-04-30T18:24:38.313703723Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea dropped invalid packet
[2025-04-30T18:24:38.313707801Z TRACE quiche_apps::client] 0.0.0.0:49231: processed 1200 bytes
[2025-04-30T18:24:38.313712479Z TRACE quiche_apps::client] 0.0.0.0:49231: recv() would block
[2025-04-30T18:24:38.313715585Z TRACE quiche_apps::client] done reading
[2025-04-30T18:24:38.313721817Z TRACE quiche_apps::client] 0.0.0.0:49231 -> 193.167.100.100:443: done writing
[2025-04-30T18:24:38.818432702Z TRACE quiche_apps::client] got 748 bytes from 193.167.100.100:443 to 0.0.0.0:49231
[2025-04-30T18:24:38.818476854Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx pkt Short dcid=1ea90276a44bfddaa590c69327bab428ab7ee1ea key_phase=false len=727 pn=4 
[2025-04-30T18:24:38.818493726Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm ACK delay=5 blocks=[4..8] ecn_counts=None
[2025-04-30T18:24:38.818501661Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea packet newly acked 4
[2025-04-30T18:24:38.818506750Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea packet newly acked 5
[2025-04-30T18:24:38.818510247Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea packet newly acked 6
[2025-04-30T18:24:38.818513072Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea packet newly acked 7
[2025-04-30T18:24:38.818516689Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea packet newly acked 8
[2025-04-30T18:24:38.818526567Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[24, f7, 07, 0e, f0, 27, 62, 0f, 49, 18, 32, dc, 3b, f0, 08, 25, 3b, e3, 3f, 62] reset_token=[ea, 2d, e5, 7c, 91, 81, 05, e9, ac, d4, 53, 8d, de, 38, 7f, 7e]
[2025-04-30T18:24:38.818537337Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[28, 30, 48, e7, e5, ce, 07, f8, e3, 8f, be, 38, 71, 9c, ae, 5e, c4, 8b, c5, 3c] reset_token=[d6, e5, 93, 84, 81, b2, 96, 7b, f8, 29, 3b, c4, 06, 1d, 5e, 74]
[2025-04-30T18:24:38.818544361Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[aa, 66, 98, 61, a2, 7f, ed, 8b, 98, f6, c4, 34, 44, ff, 21, 6a, 54, d5, 6d, 20] reset_token=[87, c6, ce, a9, 64, 8f, d2, b9, 41, 4b, 58, c3, cb, df, 48, e9]
[2025-04-30T18:24:38.818551294Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[92, db, b8, 0f, b8, 6b, 58, 5b, d3, 2f, 60, 97, 55, bf, e4, c0, c4, 0e, 6c, 32] reset_token=[e9, ef, 36, 5a, d0, 7d, d4, 8d, 8b, 16, 90, 18, e2, f8, 3a, ab]
[2025-04-30T18:24:38.818558417Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[b9, 04, b5, 34, 16, 29, 68, ea, d6, 66, 01, fa, 64, eb, 20, 00, 03, 87, 9f, 0b] reset_token=[71, fb, 08, 95, 46, 50, 1e, 6e, 3f, 9a, 60, 62, 1b, 0d, 0a, 2f]
[2025-04-30T18:24:38.818565200Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[27, df, 72, 42, ae, ba, ff, e1, 83, e7, bb, b7, 13, 5c, 96, 3a, 36, 84, 7f, 84] reset_token=[68, f8, e7, 6d, 3b, b3, 9e, 29, 11, 30, ad, c0, 76, bf, d5, 90]
[2025-04-30T18:24:38.818587130Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[be, 10, 1f, cd, db, f4, 27, 56, 56, fe, cb, 78, 5a, 0b, 92, 10, e6, 53, f8, 81] reset_token=[87, 29, 45, d0, d9, dc, d1, f6, 3f, ae, fc, 1b, 00, 5a, eb, 40]
[2025-04-30T18:24:38.818592641Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm HANDSHAKE_DONE
[2025-04-30T18:24:38.818598953Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea dropped epoch 1 state
[2025-04-30T18:24:38.818603221Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm CRYPTO off=0 len=396
[2025-04-30T18:24:38.818636583Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm STREAM id=3 off=0 len=19 fin=false
[2025-04-30T18:24:38.818671819Z TRACE quiche_apps::client] 0.0.0.0:49231: processed 748 bytes
[2025-04-30T18:24:38.818679554Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:49231
[2025-04-30T18:24:38.818683761Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx pkt Short dcid=1ea90276a44bfddaa590c69327bab428ab7ee1ea key_phase=false len=23 pn=5 
[2025-04-30T18:24:38.818689322Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T18:24:38.818695343Z TRACE quiche_apps::client] 0.0.0.0:49231: processed 44 bytes
[2025-04-30T18:24:38.818700653Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:49231
[2025-04-30T18:24:38.818704240Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx pkt Short dcid=1ea90276a44bfddaa590c69327bab428ab7ee1ea key_phase=false len=23 pn=6 
[2025-04-30T18:24:38.818708808Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T18:24:38.818713918Z TRACE quiche_apps::client] 0.0.0.0:49231: processed 44 bytes
[2025-04-30T18:24:38.818718386Z TRACE quiche_apps::client] 0.0.0.0:49231: recv() would block
[2025-04-30T18:24:38.818721582Z TRACE quiche_apps::client] done reading
[2025-04-30T18:24:38.818725910Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea stream id 3 is readable
[2025-04-30T18:24:38.818732713Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 3
[2025-04-30T18:24:38.818738574Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea open peer's control stream 3
[2025-04-30T18:24:38.818742912Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 3
[2025-04-30T18:24:38.818746669Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 3
[2025-04-30T18:24:38.818750676Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 16 bytes on stream 3
[2025-04-30T18:24:38.818754233Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(2143619779692746832, 4006540206131729473)]), additional_settings=Some([(2143619779692746832, 4006540206131729473)]) stream=3 payload_len=16
[2025-04-30T18:24:38.818763891Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea stream id 7 is readable
[2025-04-30T18:24:38.818767999Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 7
[2025-04-30T18:24:38.818772357Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea stream id 11 is readable
[2025-04-30T18:24:38.818777066Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 11
[2025-04-30T18:24:38.818790060Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:24:38.818796061Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Short dcid=861f46a8c100123b5fd85159e042a0d535e63fee key_phase=false len=5 pn=9 
[2025-04-30T18:24:38.818804878Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm ACK delay=10 blocks=[4..6] ecn_counts=None
[2025-04-30T18:24:38.818814015Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=none latest_rtt=1.371723279s srtt=1.491612191s min_rtt=1.371723279s rttvar=600.031167ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1766, delivered_time: Instant { tv_sec: 230, tv_nsec: 968808211 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 968808211 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 8, rate_sample: RateSample { delivery_rate: 943, is_app_limited: true, interval: 1.519487759s, delivered: 1433, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 229, tv_nsec: 464063325 }), send_elapsed: 1.519487759s, ack_elapsed: 1.5044318s, rtt: 1.493683221s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11313, last_update: Instant { tv_sec: 230, tv_nsec: 968808211 }, next_time: Instant { tv_sec: 230, tv_nsec: 968808211 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:24:38.818881151Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:49231 to 193.167.100.100:443
[2025-04-30T18:24:38.818887152Z TRACE quiche_apps::client] 0.0.0.0:49231 -> 193.167.100.100:443: done writing
[2025-04-30T18:24:38.819299042Z TRACE quiche_apps::client] got 1124 bytes from 193.167.100.100:443 to 0.0.0.0:49231
[2025-04-30T18:24:38.819314050Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx pkt Short dcid=1ea90276a44bfddaa590c69327bab428ab7ee1ea key_phase=false len=1103 pn=7 
[2025-04-30T18:24:38.819326524Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm STREAM id=0 off=0 len=1081 fin=true
[2025-04-30T18:24:38.819336472Z TRACE quiche_apps::client] 0.0.0.0:49231: processed 1124 bytes
[2025-04-30T18:24:38.819341913Z TRACE quiche_apps::client] got 69 bytes from 193.167.100.100:443 to 0.0.0.0:49231
[2025-04-30T18:24:38.819345279Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx pkt Short dcid=1ea90276a44bfddaa590c69327bab428ab7ee1ea key_phase=false len=48 pn=8 
[2025-04-30T18:24:38.819349557Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm STREAM id=15 off=0 len=26 fin=true
[2025-04-30T18:24:38.819355498Z TRACE quiche_apps::client] 0.0.0.0:49231: processed 69 bytes
[2025-04-30T18:24:38.819358985Z TRACE quiche_apps::client] 0.0.0.0:49231: recv() would block
[2025-04-30T18:24:38.819361359Z TRACE quiche_apps::client] done reading
[2025-04-30T18:24:38.819364134Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea stream id 0 is readable
[2025-04-30T18:24:38.819368603Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819372189Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 7 bytes on stream 0
[2025-04-30T18:24:38.819389632Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819392668Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm UNKNOWN raw_type=4478080013175906604 stream=0 payload_len=0
[2025-04-30T18:24:38.819398288Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819401464Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 7 bytes on stream 0
[2025-04-30T18:24:38.819404370Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819407605Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 18 bytes on stream 0
[2025-04-30T18:24:38.819413166Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm UNKNOWN raw_type=1758186137097845010 stream=0 payload_len=18
[2025-04-30T18:24:38.819417194Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819420099Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819423034Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 16 bytes on stream 0
[2025-04-30T18:24:38.819425369Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm HEADERS stream=0 payload_len=16
[2025-04-30T18:24:38.819428435Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T18:24:38.819430959Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-04-30T18:24:38.819434776Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[113, 117, 105, 99, 104, 101]
[2025-04-30T18:24:38.819438704Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[49, 48, 50, 52]
[2025-04-30T18:24:38.819445046Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "quiche"), ("content-length", "1024")] on stream id 0
[2025-04-30T18:24:38.819449995Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea stream id 15 is readable
[2025-04-30T18:24:38.819453381Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 15
[2025-04-30T18:24:38.819456467Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 7 bytes on stream 15
[2025-04-30T18:24:38.819460434Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea stream id 0 is readable
[2025-04-30T18:24:38.819463490Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819466375Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819469231Z TRACE quiche::h3::stream] 1ea90276a44bfddaa590c69327bab428ab7ee1ea read 1 bytes on stream 0
[2025-04-30T18:24:38.819471505Z TRACE quiche::h3] 1ea90276a44bfddaa590c69327bab428ab7ee1ea rx frm DATA stream=0 wire_payload_len=1024
[2025-04-30T18:24:38.819477506Z DEBUG quiche_apps::common] got 1024 bytes of response data on stream 0
[2025-04-30T18:24:38.819480272Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T18:24:38.819482746Z INFO  quiche_apps::common] 1/1 response(s) received in 3.014014368s, closing...
[2025-04-30T18:24:38.819490711Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1312
[2025-04-30T18:24:38.819494638Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx pkt Short dcid=861f46a8c100123b5fd85159e042a0d535e63fee key_phase=false len=11 pn=10 
[2025-04-30T18:24:38.819497253Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T18:24:38.819504998Z TRACE quiche::recovery::congestion::recovery] 1ea90276a44bfddaa590c69327bab428ab7ee1ea timer=3.916718835s latest_rtt=1.371723279s srtt=1.491612191s min_rtt=1.371723279s rttvar=600.031167ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1766, delivered_time: Instant { tv_sec: 230, tv_nsec: 968808211 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 968808211 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { delivery_rate: 943, is_app_limited: true, interval: 1.519487759s, delivered: 1433, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 229, tv_nsec: 464063325 }), send_elapsed: 1.519487759s, ack_elapsed: 1.5044318s, rtt: 1.493683221s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11313, last_update: Instant { tv_sec: 230, tv_nsec: 969511236 }, next_time: Instant { tv_sec: 230, tv_nsec: 968808211 }, max_datagram_size: 1350, last_packet_size: None, iv: 4.3313ms, 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:24:38.819549221Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:49231 to 193.167.100.100:443
[2025-04-30T18:24:38.819555352Z TRACE quiche_apps::client] 0.0.0.0:49231 -> 193.167.100.100:443: done writing
[2025-04-30T18:24:50.499368408Z TRACE quiche_apps::client] timed out
[2025-04-30T18:24:50.499402512Z TRACE quiche] 1ea90276a44bfddaa590c69327bab428ab7ee1ea draining timeout expired
[2025-04-30T18:24:50.499452416Z TRACE quiche_apps::client] done reading
[2025-04-30T18:24:50.499458777Z INFO  quiche_apps::client] connection closed, recv=7 sent=11 lost=0 retrans=1 sent_bytes=2376 recv_bytes=2963 lost_bytes=0 [local_addr=0.0.0.0:49231 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=7 sent=11 lost=0 retrans=1 rtt=1.491612191s min_rtt=Some(1.371723279s) rttvar=600.031167ms cwnd=13500 sent_bytes=2376 recv_bytes=2963 lost_bytes=0 stream_retrans_bytes=266 pmtu=1350 delivery_rate=943]