[2025-04-30T18:38:20.770043970Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-04-30T18:38:20.770362531Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:36356 with scid f87769901332311963d16d52d62aec7237be972f [2025-04-30T18:38:20.770404990Z TRACE quiche::tls] f87769901332311963d16d52d62aec7237be972f write message lvl=Initial len=266 [2025-04-30T18:38:20.770421321Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Initial version=1 dcid=e62a1c7aa8381304d72acc25bb4a37b9 scid=f87769901332311963d16d52d62aec7237be972f len=270 pn=0 [2025-04-30T18:38:20.770424457Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm CRYPTO off=0 len=266 [2025-04-30T18:38:20.770438823Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=998.932535ms 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: 342, tv_nsec: 84169730 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, 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: 342, tv_nsec: 84169730 }, next_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, 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:38:20.770498935Z TRACE quiche_apps::client] written 1200 [2025-04-30T18:38:21.770557165Z TRACE quiche_apps::client] timed out [2025-04-30T18:38:21.770588423Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f loss detection timeout expired [2025-04-30T18:38:21.770596388Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=997.774349ms 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: 342, tv_nsec: 84169730 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, 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: 342, tv_nsec: 84169730 }, next_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, 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:38:21.770619391Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:21.770660237Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Initial version=1 dcid=e62a1c7aa8381304d72acc25bb4a37b9 scid=f87769901332311963d16d52d62aec7237be972f len=270 pn=1 [2025-04-30T18:38:21.770664675Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm CRYPTO off=0 len=266 [2025-04-30T18:38:21.770682448Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=1.997953984s 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: 342, tv_nsec: 84169730 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, 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: 343, tv_nsec: 84434533 }, next_time: Instant { tv_sec: 343, tv_nsec: 84434533 }, 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:38:21.770792743Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:36356 to 193.167.100.100:443 [2025-04-30T18:38:21.770800517Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:22.279266904Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:22.279306678Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx pkt Initial version=1 dcid=f87769901332311963d16d52d62aec7237be972f scid=239ec46d239331cd token= len=120 pn=109154 [2025-04-30T18:38:22.279322748Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm ACK delay=110 blocks=[0..0] ecn_counts=None [2025-04-30T18:38:22.279330402Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f packet newly acked 0 [2025-04-30T18:38:22.279338136Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm CRYPTO off=0 len=90 [2025-04-30T18:38:22.279463339Z TRACE quiche::tls] f87769901332311963d16d52d62aec7237be972f set write secret lvl=Handshake [2025-04-30T18:38:22.279481903Z TRACE quiche::tls] f87769901332311963d16d52d62aec7237be972f set read secret lvl=Handshake [2025-04-30T18:38:22.279521437Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx pkt Handshake version=1 dcid=f87769901332311963d16d52d62aec7237be972f scid=239ec46d239331cd len=726 pn=0 [2025-04-30T18:38:22.279528520Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm CRYPTO off=0 len=702 [2025-04-30T18:38:22.279770930Z TRACE quiche::tls] f87769901332311963d16d52d62aec7237be972f write message lvl=Handshake len=36 [2025-04-30T18:38:22.279776200Z TRACE quiche::tls] f87769901332311963d16d52d62aec7237be972f set write secret lvl=OneRTT [2025-04-30T18:38:22.279784385Z TRACE quiche::tls] f87769901332311963d16d52d62aec7237be972f set read secret lvl=OneRTT [2025-04-30T18:38:22.279803090Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f 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(e62a1c7aa8381304d72acc25bb4a37b9), max_idle_timeout: 180000, stateless_reset_token: Some(135842147134704969154859354210867287175), max_udp_payload_size: 1440, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 2097152, initial_max_stream_data_bidi_remote: 65635, initial_max_stream_data_uni: 65535, initial_max_streams_bidi: 512, initial_max_streams_uni: 512, ack_delay_exponent: 3, max_ack_delay: 10, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(239ec46d239331cd), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-04-30T18:38:22.279829338Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx pkt Handshake version=1 dcid=f87769901332311963d16d52d62aec7237be972f scid=239ec46d239331cd len=294 pn=1 [2025-04-30T18:38:22.279835540Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm PING mtu_probe=None [2025-04-30T18:38:22.279840058Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm PADDING len=273 [2025-04-30T18:38:22.279853673Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1252 bytes [2025-04-30T18:38:22.279861889Z TRACE quiche_apps::client] got 439 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:22.279866267Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx pkt Short dcid=f87769901332311963d16d52d62aec7237be972f key_phase=false len=418 pn=0 [2025-04-30T18:38:22.279871827Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm CRYPTO off=0 len=125 [2025-04-30T18:38:22.279888007Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm NEW_TOKEN len=50 [2025-04-30T18:38:22.279892926Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ab, 4a, a9, 8c, 0f, d7, 2e, 3e] reset_token=[c8, 31, 9a, e4, 04, a7, 6f, 49, 32, 5a, a9, e6, d0, 11, 55, e0] [2025-04-30T18:38:22.279901102Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[e9, 64, 04, ea, 99, fb, ca, 0a] reset_token=[c6, d4, c9, 15, 5b, 5f, 47, 3f, fe, db, 99, 33, 7d, 47, 02, a2] [2025-04-30T18:38:22.279907444Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[12, 92, 39, 68, 18, f9, 3b, 6a] reset_token=[93, 83, 87, 30, 10, 85, bf, f0, e5, af, 70, 44, a9, 9e, e8, c6] [2025-04-30T18:38:22.279913274Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[5e, f9, 4e, 88, ca, a2, b1, 02] reset_token=[5c, 11, b6, e9, a6, 66, 5d, ed, 86, 0f, ab, d5, d4, 90, 47, 1d] [2025-04-30T18:38:22.279919586Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[8b, 10, 7f, c1, 62, b9, 06, 3a] reset_token=[65, 14, 1d, 3f, 42, b8, 42, 64, 0e, d0, c3, 2e, 45, 58, 5c, a3] [2025-04-30T18:38:22.279925527Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[2c, 5d, 04, 58, 80, 67, 29, f7] reset_token=[80, 3c, 86, 94, 4b, c9, 94, 9a, 91, 11, 36, f4, c8, 31, 59, 58] [2025-04-30T18:38:22.279931468Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[22, 21, ab, ae, 34, c9, 35, 63] reset_token=[ce, b7, 13, 13, d7, fc, a5, cc, bb, 78, d3, 16, c9, 2b, a3, 4d] [2025-04-30T18:38:22.279936337Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm STREAM id=3 off=0 len=9 fin=false [2025-04-30T18:38:22.279941557Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm STREAM id=7 off=0 len=1 fin=false [2025-04-30T18:38:22.279945374Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm STREAM id=11 off=0 len=1 fin=false [2025-04-30T18:38:22.279948930Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm PADDING len=4 [2025-04-30T18:38:22.279960432Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 439 bytes [2025-04-30T18:38:22.279965301Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:22.279968867Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:22.279986611Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f tx frm SETTINGS stream=2 len=18 [2025-04-30T18:38:22.280001057Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f open GREASE stream 14 [2025-04-30T18:38:22.280011967Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f tx frm GREASE stream=0 len=0 [2025-04-30T18:38:22.280019091Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f tx frm GREASE stream=0 len=18 [2025-04-30T18:38:22.280049207Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f tx frm HEADERS stream=0 len=29 fin=true [2025-04-30T18:38:22.280057202Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /qkbeuoiabg", "user-agent: quiche"] [2025-04-30T18:38:22.280126440Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f stream id 3 is readable [2025-04-30T18:38:22.280138152Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 3 [2025-04-30T18:38:22.280143021Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f open peer's control stream 3 [2025-04-30T18:38:22.280146998Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 3 [2025-04-30T18:38:22.280150745Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 3 [2025-04-30T18:38:22.280154923Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 6 bytes on stream 3 [2025-04-30T18:38:22.280158630Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f rx frm SETTINGS max_field_section=None, qpack_max_table=Some(0), qpack_blocked=Some(0) raw=Some([(1, 0), (7, 0), (8, 1)]), additional_settings=None stream=3 payload_len=6 [2025-04-30T18:38:22.280166625Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f stream id 7 is readable [2025-04-30T18:38:22.280170833Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 7 [2025-04-30T18:38:22.280175010Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f stream id 11 is readable [2025-04-30T18:38:22.280179519Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 11 [2025-04-30T18:38:22.280245201Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Initial version=1 dcid=239ec46d239331cd scid=f87769901332311963d16d52d62aec7237be972f len=9 pn=2 [2025-04-30T18:38:22.280254979Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm ACK delay=117 blocks=[109154..109154] ecn_counts=None [2025-04-30T18:38:22.280266470Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=4.017153959s latest_rtt=1.508928126s srtt=1.508928126s min_rtt=1.508928126s rttvar=754.464063ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 343, tv_nsec: 593097856 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508928126s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 342, tv_nsec: 84169730 }), send_elapsed: 0ns, ack_elapsed: 1.508928126s, rtt: 1.508928126s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11183, last_update: Instant { tv_sec: 343, tv_nsec: 593598336 }, next_time: Instant { tv_sec: 343, tv_nsec: 593598376 }, 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:38:22.280290244Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Handshake version=1 dcid=239ec46d239331cd scid=f87769901332311963d16d52d62aec7237be972f len=45 pn=3 [2025-04-30T18:38:22.280292999Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm ACK delay=57 blocks=[0..1] ecn_counts=None [2025-04-30T18:38:22.280295654Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm CRYPTO off=0 len=36 [2025-04-30T18:38:22.280301866Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=4.017119064s latest_rtt=1.508928126s srtt=1.508928126s min_rtt=1.508928126s rttvar=754.464063ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=432 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 343, tv_nsec: 593097856 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508928126s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 342, tv_nsec: 84169730 }), send_elapsed: 0ns, ack_elapsed: 1.508928126s, rtt: 1.508928126s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11183, last_update: Instant { tv_sec: 343, tv_nsec: 593995172 }, next_time: Instant { tv_sec: 343, tv_nsec: 593598376 }, max_datagram_size: 1350, last_packet_size: None, iv: 8.852723ms, 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:38:22.280317775Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f dropped epoch 0 state [2025-04-30T18:38:22.280322424Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13401 out_len=1187 left=1156 [2025-04-30T18:38:22.280333805Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Short dcid=239ec46d239331cd key_phase=false len=1161 pn=4 [2025-04-30T18:38:22.280336550Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm ACK delay=56 blocks=[0..0] ecn_counts=None [2025-04-30T18:38:22.280339175Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[9d, a2, bc, 6a, 2d, 39, b7, 80, 37, 0f, e2, be, fd, f2, bb, b3, c5, 19, 6b, 6d] reset_token=[55, 0a, 06, cf, 26, c6, 4c, 8a, 5c, cc, 2a, 66, 6d, ac, 23, 48] [2025-04-30T18:38:22.280345226Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[99, 2d, fd, e4, 92, 14, 1f, 2e, 60, e3, 57, 85, 86, 7f, 29, 7e, 33, 7a, 51, 69] reset_token=[40, 9e, 2e, cb, 09, 90, b7, 0a, f3, 58, 4a, 75, b2, d0, 88, 97] [2025-04-30T18:38:22.280350716Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[0e, 15, 19, 9b, 93, b5, 82, f1, 40, e3, f5, 97, 3f, 61, f5, fe, c0, af, 3a, de] reset_token=[bf, fc, 28, 0b, 5e, 51, b5, eb, 3c, 21, 71, 72, 84, b7, 87, ba] [2025-04-30T18:38:22.280356347Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[37, 6b, 63, 35, e1, fd, bc, bd, 76, 75, 6f, 55, d2, 49, d0, 85, c8, 38, 9a, a0] reset_token=[bf, 0d, df, d3, 56, 61, 4a, 75, a1, 76, f1, 8a, a6, 62, cc, f7] [2025-04-30T18:38:22.280361757Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[74, 10, f6, 79, 34, 5d, 12, 7f, c5, 8d, 57, e1, 47, 74, bf, ac, 77, bc, af, 13] reset_token=[0f, 93, f3, 6e, 83, dc, a1, e4, 62, 82, a4, da, c4, 30, 7b, 6f] [2025-04-30T18:38:22.280367418Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[a9, 49, fb, 17, 64, e0, 0c, 59, 54, b7, 1e, d6, 12, 1d, e6, 65, b5, 8e, b1, 61] reset_token=[f3, 7d, 26, ba, be, 45, 0f, ca, d6, f3, 54, 77, f1, 18, 14, 97] [2025-04-30T18:38:22.280372748Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[ae, 2e, 6f, 68, e4, 2e, 1c, 95, 21, 95, bc, fc, ed, ff, 2b, 86, 27, 50, 6f, f5] reset_token=[be, f7, c2, 62, 28, 95, f0, 5a, 95, a0, 5e, 67, 8e, dc, 42, 32] [2025-04-30T18:38:22.280377897Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm STREAM id=2 off=0 len=19 fin=false [2025-04-30T18:38:22.280380472Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm PADDING len=852 [2025-04-30T18:38:22.280389238Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=4.526592411s latest_rtt=1.508928126s srtt=1.508928126s min_rtt=1.508928126s rttvar=754.464063ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1286 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 343, tv_nsec: 593097856 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508928126s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 342, tv_nsec: 84169730 }), send_elapsed: 0ns, ack_elapsed: 1.508928126s, rtt: 1.508928126s } } pacer=Pacer { enabled: true, capacity: 13500, used: 1187, rate: 11183, last_update: Instant { tv_sec: 343, tv_nsec: 593995172 }, next_time: Instant { tv_sec: 343, tv_nsec: 602847895 }, max_datagram_size: 1350, last_packet_size: Some(1187), 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:38:22.280446444Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:36356 to 193.167.100.100:443 [2025-04-30T18:38:22.280453107Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12214 out_len=1350 left=1324 [2025-04-30T18:38:22.280456523Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Short dcid=239ec46d239331cd key_phase=false len=6 pn=5 [2025-04-30T18:38:22.280458937Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm STREAM id=6 off=0 len=1 fin=false [2025-04-30T18:38:22.280464418Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=4.526517172s latest_rtt=1.508928126s srtt=1.508928126s min_rtt=1.508928126s rttvar=754.464063ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1318 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 343, tv_nsec: 593097856 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508928126s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 342, tv_nsec: 84169730 }), send_elapsed: 0ns, ack_elapsed: 1.508928126s, rtt: 1.508928126s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11183, last_update: Instant { tv_sec: 343, tv_nsec: 594248252 }, next_time: Instant { tv_sec: 343, tv_nsec: 602847895 }, max_datagram_size: 1350, last_packet_size: None, iv: 109.004739ms, 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:38:22.280490316Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:36356 to 193.167.100.100:443 [2025-04-30T18:38:22.280494724Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12182 out_len=1350 left=1324 [2025-04-30T18:38:22.280497760Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Short dcid=239ec46d239331cd key_phase=false len=6 pn=6 [2025-04-30T18:38:22.280500154Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm STREAM id=10 off=0 len=1 fin=false [2025-04-30T18:38:22.280504612Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=4.526477047s latest_rtt=1.508928126s srtt=1.508928126s min_rtt=1.508928126s rttvar=754.464063ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 343, tv_nsec: 593097856 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508928126s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 342, tv_nsec: 84169730 }), send_elapsed: 0ns, ack_elapsed: 1.508928126s, rtt: 1.508928126s } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 11183, last_update: Instant { tv_sec: 343, tv_nsec: 594248252 }, next_time: Instant { tv_sec: 343, tv_nsec: 711852634 }, max_datagram_size: 1350, last_packet_size: Some(32), 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:38:22.280534097Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:36356 to 193.167.100.100:443 [2025-04-30T18:38:22.280539217Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12150 out_len=1350 left=1324 [2025-04-30T18:38:22.280542563Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Short dcid=239ec46d239331cd key_phase=false len=72 pn=7 [2025-04-30T18:38:22.280545007Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm STREAM id=0 off=0 len=67 fin=true [2025-04-30T18:38:22.280550097Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=4.526431512s latest_rtt=1.508928126s srtt=1.508928126s min_rtt=1.508928126s rttvar=754.464063ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1448 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 343, tv_nsec: 593097856 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508928126s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 342, tv_nsec: 84169730 }), send_elapsed: 0ns, ack_elapsed: 1.508928126s, rtt: 1.508928126s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11183, last_update: Instant { tv_sec: 343, tv_nsec: 594334933 }, next_time: Instant { tv_sec: 343, tv_nsec: 711852634 }, max_datagram_size: 1350, last_packet_size: None, iv: 11.624788ms, 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:38:22.280574763Z TRACE quiche_apps::client] written 98 bytes from 0.0.0.0:36356 to 193.167.100.100:443 [2025-04-30T18:38:22.280579271Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12052 out_len=1350 left=1324 [2025-04-30T18:38:22.280582357Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Short dcid=239ec46d239331cd key_phase=false len=31 pn=8 [2025-04-30T18:38:22.280584701Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm STREAM id=14 off=0 len=26 fin=true [2025-04-30T18:38:22.280589841Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=4.526391789s latest_rtt=1.508928126s srtt=1.508928126s min_rtt=1.508928126s rttvar=754.464063ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1505 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 343, tv_nsec: 593097856 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508928126s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 342, tv_nsec: 84169730 }), send_elapsed: 0ns, ack_elapsed: 1.508928126s, rtt: 1.508928126s } } pacer=Pacer { enabled: true, capacity: 13500, used: 57, rate: 11183, last_update: Instant { tv_sec: 343, tv_nsec: 594334933 }, next_time: Instant { tv_sec: 343, tv_nsec: 723477422 }, max_datagram_size: 1350, last_packet_size: Some(57), 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:38:22.280618404Z TRACE quiche_apps::client] written 57 bytes from 0.0.0.0:36356 to 193.167.100.100:443 [2025-04-30T18:38:22.280621990Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:22.280631498Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:22.280635285Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx pkt Short dcid=f87769901332311963d16d52d62aec7237be972f key_phase=false len=1329 pn=1 [2025-04-30T18:38:22.280640785Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm PING mtu_probe=None [2025-04-30T18:38:22.280647818Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm PADDING len=1311 [2025-04-30T18:38:22.280653128Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1350 bytes [2025-04-30T18:38:22.280656615Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:22.280658969Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:22.280664690Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11995 out_len=1350 left=1319 [2025-04-30T18:38:22.280667515Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Short dcid=239ec46d239331cd key_phase=false len=5 pn=9 [2025-04-30T18:38:22.280669889Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm ACK delay=3 blocks=[0..1] ecn_counts=None [2025-04-30T18:38:22.280675430Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=4.52630619s latest_rtt=1.508928126s srtt=1.508928126s min_rtt=1.508928126s rttvar=754.464063ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1505 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 343, tv_nsec: 593097856 }, first_sent_time: Instant { tv_sec: 342, tv_nsec: 84169730 }, end_of_app_limited: 7, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508928126s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 342, tv_nsec: 84169730 }), send_elapsed: 0ns, ack_elapsed: 1.508928126s, rtt: 1.508928126s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11183, last_update: Instant { tv_sec: 343, tv_nsec: 594460436 }, next_time: Instant { tv_sec: 343, tv_nsec: 723477422 }, max_datagram_size: 1350, last_packet_size: None, iv: 5.097022ms, 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:38:22.280712939Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:36356 to 193.167.100.100:443 [2025-04-30T18:38:22.280716456Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:23.273678186Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:23.273716066Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f dropped invalid packet [2025-04-30T18:38:23.273721587Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1252 bytes [2025-04-30T18:38:23.273727006Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:23.273730353Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:23.273742135Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:23.774354891Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:23.774407909Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f dropped invalid packet [2025-04-30T18:38:23.774413680Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1252 bytes [2025-04-30T18:38:23.774419270Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:23.774422676Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:23.774433467Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:23.791655607Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:23.791688909Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx pkt Short dcid=f87769901332311963d16d52d62aec7237be972f key_phase=false len=1186 pn=2 [2025-04-30T18:38:23.791704708Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm ACK delay=981 blocks=[4..9] ecn_counts=None [2025-04-30T18:38:23.791712012Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f packet newly acked 4 [2025-04-30T18:38:23.791717151Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f packet newly acked 5 [2025-04-30T18:38:23.791720678Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f packet newly acked 6 [2025-04-30T18:38:23.791723573Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f packet newly acked 7 [2025-04-30T18:38:23.791726980Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f packet newly acked 8 [2025-04-30T18:38:23.791729795Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f packet newly acked 9 [2025-04-30T18:38:23.791735445Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm HANDSHAKE_DONE [2025-04-30T18:38:23.791742078Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f dropped epoch 1 state [2025-04-30T18:38:23.791746216Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm STREAM id=0 off=0 len=23 fin=false [2025-04-30T18:38:23.791752096Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm STREAM id=0 off=23 len=1024 fin=true [2025-04-30T18:38:23.791757286Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f rx frm PADDING len=107 [2025-04-30T18:38:23.791790628Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1207 bytes [2025-04-30T18:38:23.791796248Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:23.791799615Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:23.791806417Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f stream id 0 is readable [2025-04-30T18:38:23.791837475Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 0 [2025-04-30T18:38:23.791842945Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 0 [2025-04-30T18:38:23.791846782Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 0 [2025-04-30T18:38:23.791850860Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 17 bytes on stream 0 [2025-04-30T18:38:23.791854546Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f rx frm HEADERS stream=0 payload_len=17 [2025-04-30T18:38:23.791858975Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0 [2025-04-30T18:38:23.791862091Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true [2025-04-30T18:38:23.791865417Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[72, 51, 90, 101, 114, 111, 47, 49, 46, 48] [2025-04-30T18:38:23.791869805Z TRACE quiche::h3::qpack::decoder] Indexed index=53 static=true [2025-04-30T18:38:23.791879433Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "H3Zero/1.0"), ("content-type", "text/plain")] on stream id 0 [2025-04-30T18:38:23.791885724Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f stream id 0 is readable [2025-04-30T18:38:23.791892878Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 0 [2025-04-30T18:38:23.791896775Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 0 [2025-04-30T18:38:23.791900993Z TRACE quiche::h3::stream] f87769901332311963d16d52d62aec7237be972f read 1 bytes on stream 0 [2025-04-30T18:38:23.791903898Z TRACE quiche::h3] f87769901332311963d16d52d62aec7237be972f rx frm DATA stream=0 wire_payload_len=1024 [2025-04-30T18:38:23.791911091Z DEBUG quiche_apps::common] got 1024 bytes of response data on stream 0 [2025-04-30T18:38:23.791914578Z DEBUG quiche_apps::common] 1/1 responses received [2025-04-30T18:38:23.791917624Z INFO quiche_apps::common] 1/1 response(s) received in 3.021414941s, closing... [2025-04-30T18:38:23.791928804Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324 [2025-04-30T18:38:23.791934695Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx pkt Short dcid=239ec46d239331cd key_phase=false len=11 pn=10 [2025-04-30T18:38:23.791942650Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-04-30T18:38:23.791953570Z TRACE quiche::recovery::congestion::recovery] f87769901332311963d16d52d62aec7237be972f timer=3.893348506s latest_rtt=1.382004709s srtt=1.493062698s min_rtt=1.382004709s rttvar=597.578901ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1739, delivered_time: Instant { tv_sec: 345, tv_nsec: 110819068 }, first_sent_time: Instant { tv_sec: 345, tv_nsec: 110819068 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 9, rate_sample: RateSample { delivery_rate: 925, is_app_limited: true, interval: 1.518678165s, delivered: 1406, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 593097856 }), send_elapsed: 1.518678165s, ack_elapsed: 1.512384275s, rtt: 1.502634236s } } pacer=Pacer { enabled: true, capacity: 13500, used: 37, rate: 11302, last_update: Instant { tv_sec: 345, tv_nsec: 105722046 }, next_time: Instant { tv_sec: 345, tv_nsec: 110819068 }, max_datagram_size: 1350, last_packet_size: Some(37), 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:38:23.792024282Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:36356 to 193.167.100.100:443 [2025-04-30T18:38:23.792029461Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:23.979926252Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:23.979958803Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1207 bytes [2025-04-30T18:38:23.979965285Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:23.979968751Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:23.979977587Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:24.168193244Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:24.168251502Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1207 bytes [2025-04-30T18:38:24.168257974Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:24.168261400Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:24.168269315Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:24.356502348Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:24.356536392Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1207 bytes [2025-04-30T18:38:24.356542723Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:24.356559535Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:24.356568191Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:24.544580335Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:24.544605793Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1207 bytes [2025-04-30T18:38:24.544611954Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:24.544615381Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:24.544624317Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:24.732928705Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:24.732967417Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1207 bytes [2025-04-30T18:38:24.732975292Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:24.732980572Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:24.732990650Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:24.921232465Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:24.921269454Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1207 bytes [2025-04-30T18:38:24.921277609Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:24.921283049Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:24.921293739Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:25.109468388Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:25.109505226Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 1207 bytes [2025-04-30T18:38:25.109513411Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:25.109518741Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:25.109529381Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:25.293025751Z TRACE quiche_apps::client] got 42 bytes from 193.167.100.100:443 to 0.0.0.0:36356 [2025-04-30T18:38:25.293061287Z TRACE quiche_apps::client] 0.0.0.0:36356: processed 42 bytes [2025-04-30T18:38:25.293068961Z TRACE quiche_apps::client] 0.0.0.0:36356: recv() would block [2025-04-30T18:38:25.293073850Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:25.293083719Z TRACE quiche_apps::client] 0.0.0.0:36356 -> 193.167.100.100:443: done writing [2025-04-30T18:38:35.448223886Z TRACE quiche_apps::client] timed out [2025-04-30T18:38:35.448263470Z TRACE quiche] f87769901332311963d16d52d62aec7237be972f draining timeout expired [2025-04-30T18:38:35.448311278Z TRACE quiche_apps::client] done reading [2025-04-30T18:38:35.448317580Z INFO quiche_apps::client] connection closed, recv=6 sent=11 lost=0 retrans=1 sent_bytes=2303 recv_bytes=4248 lost_bytes=0 [local_addr=0.0.0.0:36356 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=6 sent=11 lost=0 retrans=1 rtt=1.493062698s min_rtt=Some(1.382004709s) rttvar=597.578901ms cwnd=13500 sent_bytes=2303 recv_bytes=4248 lost_bytes=0 stream_retrans_bytes=266 pmtu=1350 delivery_rate=925]