[2025-09-12T02:38:33.671039403Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-09-12T02:38:33.671328895Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:51077 with scid f329f7bd428caad4886893139fa3623d43b02a06 [2025-09-12T02:38:33.671385802Z TRACE quiche::tls] f329f7bd428caad4886893139fa3623d43b02a06 write message lvl=Initial len=266 [2025-09-12T02:38:33.671405910Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Initial version=1 dcid=18399d47d94a1f6598d785ba9e248044 scid=f329f7bd428caad4886893139fa3623d43b02a06 len=270 pn=0 [2025-09-12T02:38:33.671409326Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm CRYPTO off=0 len=266 [2025-09-12T02:38:33.671423603Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=998.911935ms 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: 700, tv_nsec: 538989750 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 700, tv_nsec: 538989750 }, next_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, 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-09-12T02:38:33.671475941Z TRACE quiche_apps::client] written 1200 [2025-09-12T02:38:34.671530362Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:34.671558795Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 loss detection timeout expired [2025-09-12T02:38:34.671565057Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=997.769409ms 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: 700, tv_nsec: 538989750 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 700, tv_nsec: 538989750 }, next_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, 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-09-12T02:38:34.671590705Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:34.671627614Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Initial version=1 dcid=18399d47d94a1f6598d785ba9e248044 scid=f329f7bd428caad4886893139fa3623d43b02a06 len=270 pn=1 [2025-09-12T02:38:34.671632173Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm CRYPTO off=0 len=266 [2025-09-12T02:38:34.671651309Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=1.997953252s 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: 700, tv_nsec: 538989750 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 701, tv_nsec: 539258593 }, next_time: Instant { tv_sec: 701, tv_nsec: 539258593 }, 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-09-12T02:38:34.671727071Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:34.671734064Z TRACE quiche_apps::client] 0.0.0.0:51077 -> 193.167.100.100:443: done writing [2025-09-12T02:38:34.708810997Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:51077 [2025-09-12T02:38:34.708839009Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx pkt Initial version=1 dcid=f329f7bd428caad4886893139fa3623d43b02a06 scid=a69482df0e88cab0c84feeb56af981402e599cb8 token= len=119 pn=2 [2025-09-12T02:38:34.708860259Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm ACK delay=124961 blocks=[0..0] ecn_counts=None [2025-09-12T02:38:34.708869456Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 packet newly acked 0 [2025-09-12T02:38:34.708878182Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm CRYPTO off=0 len=90 [2025-09-12T02:38:34.709044784Z TRACE quiche::tls] f329f7bd428caad4886893139fa3623d43b02a06 set write secret lvl=Handshake [2025-09-12T02:38:34.709060384Z TRACE quiche::tls] f329f7bd428caad4886893139fa3623d43b02a06 set read secret lvl=Handshake [2025-09-12T02:38:34.709094978Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 dropped invalid packet [2025-09-12T02:38:34.709099126Z TRACE quiche_apps::client] 0.0.0.0:51077: processed 1200 bytes [2025-09-12T02:38:34.709104967Z TRACE quiche_apps::client] 0.0.0.0:51077: recv() would block [2025-09-12T02:38:34.709108243Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:34.709123482Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Initial version=1 dcid=a69482df0e88cab0c84feeb56af981402e599cb8 scid=f329f7bd428caad4886893139fa3623d43b02a06 len=5 pn=2 [2025-09-12T02:38:34.709127299Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm ACK delay=36 blocks=[2..2] ecn_counts=None [2025-09-12T02:38:34.709136927Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=3.074958904s latest_rtt=1.03749715s srtt=1.03749715s min_rtt=1.03749715s rttvar=518.748575ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 701, tv_nsec: 576486900 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 1.03749715s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 700, tv_nsec: 538989750 }), send_elapsed: 0ns, ack_elapsed: 1.03749715s, rtt: 1.03749715s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 16265, last_update: Instant { tv_sec: 701, tv_nsec: 539258593 }, next_time: Instant { tv_sec: 701, tv_nsec: 539258593 }, 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-09-12T02:38:34.709197300Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:34.709204734Z TRACE quiche_apps::client] 0.0.0.0:51077 -> 193.167.100.100:443: done writing [2025-09-12T02:38:35.708821133Z TRACE quiche_apps::client] got 767 bytes from 193.167.100.100:443 to 0.0.0.0:51077 [2025-09-12T02:38:35.708856098Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx pkt Handshake version=1 dcid=f329f7bd428caad4886893139fa3623d43b02a06 scid=a69482df0e88cab0c84feeb56af981402e599cb8 len=718 pn=3 [2025-09-12T02:38:35.708878430Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm CRYPTO off=0 len=697 [2025-09-12T02:38:35.709227855Z TRACE quiche::tls] f329f7bd428caad4886893139fa3623d43b02a06 write message lvl=Handshake len=36 [2025-09-12T02:38:35.709234898Z TRACE quiche::tls] f329f7bd428caad4886893139fa3623d43b02a06 set write secret lvl=OneRTT [2025-09-12T02:38:35.709246579Z TRACE quiche::tls] f329f7bd428caad4886893139fa3623d43b02a06 set read secret lvl=OneRTT [2025-09-12T02:38:35.709269312Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 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(18399d47d94a1f6598d785ba9e248044), 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(a69482df0e88cab0c84feeb56af981402e599cb8), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-09-12T02:38:35.709305801Z TRACE quiche_apps::client] 0.0.0.0:51077: processed 767 bytes [2025-09-12T02:38:35.709312934Z TRACE quiche_apps::client] got 70 bytes from 193.167.100.100:443 to 0.0.0.0:51077 [2025-09-12T02:38:35.709317993Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx pkt Handshake version=1 dcid=f329f7bd428caad4886893139fa3623d43b02a06 scid=a69482df0e88cab0c84feeb56af981402e599cb8 len=21 pn=4 [2025-09-12T02:38:35.709325417Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm PING mtu_probe=None [2025-09-12T02:38:35.709329184Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm PADDING len=3 [2025-09-12T02:38:35.709337540Z TRACE quiche_apps::client] 0.0.0.0:51077: processed 70 bytes [2025-09-12T02:38:35.709341958Z TRACE quiche_apps::client] 0.0.0.0:51077: recv() would block [2025-09-12T02:38:35.709345244Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:35.709390559Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 tx frm SETTINGS stream=2 len=18 [2025-09-12T02:38:35.709407150Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 open GREASE stream 14 [2025-09-12T02:38:35.709417159Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 tx frm GREASE stream=0 len=0 [2025-09-12T02:38:35.709424493Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 tx frm GREASE stream=0 len=18 [2025-09-12T02:38:35.709433830Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 tx frm HEADERS stream=0 len=30 fin=true [2025-09-12T02:38:35.709439932Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /dcfpxvfgoq", "user-agent: quiche"] [2025-09-12T02:38:35.709522617Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Handshake version=1 dcid=a69482df0e88cab0c84feeb56af981402e599cb8 scid=f329f7bd428caad4886893139fa3623d43b02a06 len=45 pn=3 [2025-09-12T02:38:35.709527436Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm ACK delay=25 blocks=[3..4] ecn_counts=None [2025-09-12T02:38:35.709537404Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm CRYPTO off=0 len=36 [2025-09-12T02:38:35.709548946Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=2.074546784s latest_rtt=1.03749715s srtt=1.03749715s min_rtt=1.03749715s rttvar=518.748575ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=444 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 701, tv_nsec: 576486900 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 1.03749715s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 700, tv_nsec: 538989750 }), send_elapsed: 0ns, ack_elapsed: 1.03749715s, rtt: 1.03749715s } } pacer=Pacer { enabled: true, capacity: 13500, used: 111, rate: 16265, last_update: Instant { tv_sec: 702, tv_nsec: 576920038 }, next_time: Instant { tv_sec: 702, tv_nsec: 576920078 }, max_datagram_size: 1350, last_packet_size: Some(111), 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-09-12T02:38:35.709569705Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 dropped epoch 0 state [2025-09-12T02:38:35.709579283Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=304 pn=4 [2025-09-12T02:38:35.709582078Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e6, 18, 77, 7e, 9d, 6e, 9f, 27, 51, 0b, ec, 8d, 98, 5b, 90, 6d, f7, 4b, 54, d9] reset_token=[d3, d4, e6, 6b, 8a, 7b, b3, 37, 6e, 77, c2, df, da, 19, 49, e9] [2025-09-12T02:38:35.709589021Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[e4, 9c, d2, b2, 83, ad, b5, a5, 1d, 14, 57, 46, d5, fa, 1b, 96, 7d, 86, 77, 70] reset_token=[45, 44, b7, aa, a9, eb, de, e5, 2d, 9f, fb, 76, c7, b5, a7, 7a] [2025-09-12T02:38:35.709594622Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[e1, 05, 5a, a9, f6, f5, 12, 39, 3a, 05, 54, 80, 65, c0, fc, 5c, 7c, 9f, 57, 2e] reset_token=[07, df, 23, 21, 68, 72, a1, 95, 80, 84, 59, 7c, f3, 40, 00, 04] [2025-09-12T02:38:35.709600392Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[fc, 18, 26, 22, af, e7, df, b9, a3, 34, 5c, cd, 25, 00, a5, 29, 2c, 9c, 71, 4f] reset_token=[33, cb, ad, 52, 0f, f9, 20, 9b, e4, 44, 97, 02, 79, 47, fa, 37] [2025-09-12T02:38:35.709606123Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[51, 1b, 87, d1, 50, 6c, de, 68, 62, ea, 04, 36, b9, 78, 55, 09, 24, 40, 9a, 5c] reset_token=[21, cb, fa, c6, 67, 7f, 31, 2b, 04, a7, 8b, fa, 84, 99, 7c, bf] [2025-09-12T02:38:35.709611703Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[a3, 87, 03, 78, 96, 69, 19, e8, 3e, f4, c6, 5a, 98, 77, 4c, 36, 75, 08, 7b, 91] reset_token=[5f, 46, 32, ba, 7f, 9e, bf, fb, 20, 19, 87, 31, 4d, 79, 96, 70] [2025-09-12T02:38:35.709617304Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[b6, de, 10, 11, 7d, 61, 13, 1a, d8, 1f, d1, 87, 6a, 04, 74, e9, 3d, b1, 14, c4] reset_token=[43, ae, 74, d2, 55, 57, 63, f7, 1d, 0d, e2, d4, a7, 54, 18, 7c] [2025-09-12T02:38:35.709622804Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm STREAM id=2 off=0 len=19 fin=false [2025-09-12T02:38:35.709651197Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=3.112350637s latest_rtt=1.03749715s srtt=1.03749715s min_rtt=1.03749715s rttvar=518.748575ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=453 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 701, tv_nsec: 576486900 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 1.03749715s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 700, tv_nsec: 538989750 }), send_elapsed: 0ns, ack_elapsed: 1.03749715s, rtt: 1.03749715s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 16265, last_update: Instant { tv_sec: 702, tv_nsec: 577164386 }, next_time: Instant { tv_sec: 702, tv_nsec: 576920078 }, max_datagram_size: 1350, last_packet_size: None, iv: 27.851214ms, 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-09-12T02:38:35.709691693Z TRACE quiche_apps::client] written 453 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:35.709699999Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=6 pn=5 [2025-09-12T02:38:35.709702924Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm STREAM id=6 off=0 len=1 fin=false [2025-09-12T02:38:35.709708385Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=3.112293559s latest_rtt=1.03749715s srtt=1.03749715s min_rtt=1.03749715s rttvar=518.748575ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=497 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 701, tv_nsec: 576486900 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 1.03749715s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 700, tv_nsec: 538989750 }), send_elapsed: 0ns, ack_elapsed: 1.03749715s, rtt: 1.03749715s } } pacer=Pacer { enabled: true, capacity: 13500, used: 44, rate: 16265, last_update: Instant { tv_sec: 702, tv_nsec: 577164386 }, next_time: Instant { tv_sec: 702, tv_nsec: 605200606 }, 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-09-12T02:38:35.709733943Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:35.709739763Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=6 pn=6 [2025-09-12T02:38:35.709742328Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm STREAM id=10 off=0 len=1 fin=false [2025-09-12T02:38:35.709746977Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=3.112255037s latest_rtt=1.03749715s srtt=1.03749715s min_rtt=1.03749715s rttvar=518.748575ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=541 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 701, tv_nsec: 576486900 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 1.03749715s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 700, tv_nsec: 538989750 }), send_elapsed: 0ns, ack_elapsed: 1.03749715s, rtt: 1.03749715s } } pacer=Pacer { enabled: true, capacity: 13500, used: 88, rate: 16265, last_update: Instant { tv_sec: 702, tv_nsec: 577164386 }, next_time: Instant { tv_sec: 702, tv_nsec: 605200606 }, 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-09-12T02:38:35.709774969Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:35.709781051Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=73 pn=7 [2025-09-12T02:38:35.709783686Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:38:35.709790028Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=3.112211616s latest_rtt=1.03749715s srtt=1.03749715s min_rtt=1.03749715s rttvar=518.748575ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=652 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 701, tv_nsec: 576486900 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 1.03749715s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 700, tv_nsec: 538989750 }), send_elapsed: 0ns, ack_elapsed: 1.03749715s, rtt: 1.03749715s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 16265, last_update: Instant { tv_sec: 702, tv_nsec: 577431736 }, next_time: Instant { tv_sec: 702, tv_nsec: 605200606 }, max_datagram_size: 1350, last_packet_size: None, iv: 12.23486ms, 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-09-12T02:38:35.709828389Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:35.709836204Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=31 pn=8 [2025-09-12T02:38:35.709838909Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm STREAM id=14 off=0 len=26 fin=true [2025-09-12T02:38:35.709845141Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=3.112156834s latest_rtt=1.03749715s srtt=1.03749715s min_rtt=1.03749715s rttvar=518.748575ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=721 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 701, tv_nsec: 576486900 }, first_sent_time: Instant { tv_sec: 700, tv_nsec: 538989750 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 1.03749715s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 700, tv_nsec: 538989750 }), send_elapsed: 0ns, ack_elapsed: 1.03749715s, rtt: 1.03749715s } } pacer=Pacer { enabled: true, capacity: 13500, used: 69, rate: 16265, last_update: Instant { tv_sec: 702, tv_nsec: 577431736 }, next_time: Instant { tv_sec: 702, tv_nsec: 617435466 }, 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-09-12T02:38:35.709874105Z TRACE quiche_apps::client] written 69 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:35.709877862Z TRACE quiche_apps::client] 0.0.0.0:51077 -> 193.167.100.100:443: done writing [2025-09-12T02:38:35.741906828Z TRACE quiche_apps::client] got 750 bytes from 193.167.100.100:443 to 0.0.0.0:51077 [2025-09-12T02:38:35.741925543Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx pkt Short dcid=f329f7bd428caad4886893139fa3623d43b02a06 key_phase=false len=729 pn=5 [2025-09-12T02:38:35.741938818Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm ACK delay=5 blocks=[4..6, 8..8] ecn_counts=None [2025-09-12T02:38:35.741946713Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 packet newly acked 4 [2025-09-12T02:38:35.741952865Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 packet newly acked 5 [2025-09-12T02:38:35.741958956Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 packet newly acked 6 [2025-09-12T02:38:35.741964246Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 packet newly acked 8 [2025-09-12T02:38:35.741975848Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[06, c8, 55, 7c, 3d, 42, 06, ae, 55, 12, 42, 8f, 4e, 02, a1, 5c, ab, 5d, 9d, a5] reset_token=[2a, 34, 5e, d6, a8, ed, 5f, f2, d1, 00, d1, 9a, fe, 5a, 92, 4f] [2025-09-12T02:38:35.741990645Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[c0, 7e, 01, 2d, 1b, 15, 70, d9, 38, 5a, e4, 77, 6a, 73, 6d, fe, 19, 53, 7f, 72] reset_token=[c3, fb, 0b, b0, ae, d3, 3c, 68, ce, b1, 08, 68, 85, d9, 2c, d9] [2025-09-12T02:38:35.742003209Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[4e, 72, a0, e4, 57, aa, 67, 1a, 9d, a8, 79, f3, 5b, b6, 83, 00, f0, 42, 21, 93] reset_token=[b5, f4, 3a, 76, b9, 51, b3, 59, 5e, 06, 14, ac, 19, 58, 58, 02] [2025-09-12T02:38:35.742016273Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[31, 4b, 7a, c4, 8a, c7, 6d, d4, 9c, 07, a9, 5b, df, b3, 6f, fb, 1b, e6, c3, f8] reset_token=[74, 32, ee, 6a, 7a, 69, ef, 82, 1a, 12, ce, b9, 8d, b9, f4, 99] [2025-09-12T02:38:35.742117754Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[3c, 20, 9f, 50, dc, fc, 42, 60, 67, bf, 36, 5c, 93, f9, cc, c7, bc, 0b, 04, 85] reset_token=[fd, 7f, 85, 0f, e0, 01, 0a, 74, 1f, 69, 40, d7, 84, 1c, 9e, ef] [2025-09-12T02:38:35.742132753Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[e5, 82, e3, 55, a4, 1c, bb, 3d, 41, b3, 6a, 0c, ba, 50, e3, 3e, a3, 9d, 57, 6e] reset_token=[42, 5f, 26, d7, b4, a2, 51, 2c, ea, dd, 3f, a5, d3, cc, 54, 49] [2025-09-12T02:38:35.742145096Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[b2, 3b, 36, 41, 24, f8, 8c, 41, 66, bd, a2, d4, f1, 59, 20, 59, cc, 88, 78, 6a] reset_token=[b0, ff, 4c, ca, 8b, fb, ca, 2f, 2b, 1a, fc, fb, a9, ba, fe, 02] [2025-09-12T02:38:35.742154944Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm HANDSHAKE_DONE [2025-09-12T02:38:35.742159823Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 dropped epoch 1 state [2025-09-12T02:38:35.742163410Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm CRYPTO off=0 len=396 [2025-09-12T02:38:35.742187615Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm STREAM id=3 off=0 len=19 fin=false [2025-09-12T02:38:35.742222882Z TRACE quiche_apps::client] 0.0.0.0:51077: processed 750 bytes [2025-09-12T02:38:35.742233932Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:51077 [2025-09-12T02:38:35.742247477Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx pkt Short dcid=f329f7bd428caad4886893139fa3623d43b02a06 key_phase=false len=23 pn=7 [2025-09-12T02:38:35.742254641Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm STREAM id=11 off=0 len=1 fin=false [2025-09-12T02:38:35.742261624Z TRACE quiche_apps::client] 0.0.0.0:51077: processed 44 bytes [2025-09-12T02:38:35.742266643Z TRACE quiche_apps::client] got 69 bytes from 193.167.100.100:443 to 0.0.0.0:51077 [2025-09-12T02:38:35.742270340Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx pkt Short dcid=f329f7bd428caad4886893139fa3623d43b02a06 key_phase=false len=48 pn=8 [2025-09-12T02:38:35.742275269Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm STREAM id=15 off=0 len=26 fin=true [2025-09-12T02:38:35.742280930Z TRACE quiche_apps::client] 0.0.0.0:51077: processed 69 bytes [2025-09-12T02:38:35.742285098Z TRACE quiche_apps::client] 0.0.0.0:51077: recv() would block [2025-09-12T02:38:35.742288124Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:35.742291310Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 stream id 3 is readable [2025-09-12T02:38:35.742297341Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 3 [2025-09-12T02:38:35.742302410Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 open peer's control stream 3 [2025-09-12T02:38:35.742306388Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 3 [2025-09-12T02:38:35.742310175Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 3 [2025-09-12T02:38:35.742314193Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 16 bytes on stream 3 [2025-09-12T02:38:35.742317699Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(2297918689385184080, 1908239887336955333)]), additional_settings=Some([(2297918689385184080, 1908239887336955333)]) stream=3 payload_len=16 [2025-09-12T02:38:35.742325874Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 stream id 11 is readable [2025-09-12T02:38:35.742330012Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 11 [2025-09-12T02:38:35.742334390Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 stream id 15 is readable [2025-09-12T02:38:35.742338788Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 15 [2025-09-12T02:38:35.742342546Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 7 bytes on stream 15 [2025-09-12T02:38:35.742381488Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=7 pn=9 [2025-09-12T02:38:35.742386217Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm ACK delay=13 blocks=[5..5, 7..8] ecn_counts=None [2025-09-12T02:38:35.742395345Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=1.016437376s latest_rtt=0ns srtt=907.810006ms min_rtt=0ns rttvar=648.435718ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=111 app_limited=true congestion_recovery_start_time=None Rate { delivered: 832, delivered_time: Instant { tv_sec: 702, tv_nsec: 609576720 }, first_sent_time: Instant { tv_sec: 702, tv_nsec: 617435466 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 8, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 2.078445716s, delivered: 499, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 701, tv_nsec: 576486900 }), send_elapsed: 2.078445716s, ack_elapsed: 1.03308982s, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 18588, last_update: Instant { tv_sec: 702, tv_nsec: 610027836 }, next_time: Instant { tv_sec: 702, tv_nsec: 617435466 }, max_datagram_size: 1350, last_packet_size: None, iv: 3.712072ms, 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-09-12T02:38:35.742443244Z TRACE quiche_apps::client] written 45 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:35.742448825Z TRACE quiche_apps::client] 0.0.0.0:51077 -> 193.167.100.100:443: done writing [2025-09-12T02:38:36.109336305Z TRACE quiche_apps::client] got 54 bytes from 193.167.100.100:443 to 0.0.0.0:51077 [2025-09-12T02:38:36.109399173Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx pkt Short dcid=f329f7bd428caad4886893139fa3623d43b02a06 key_phase=false len=33 pn=11 [2025-09-12T02:38:36.109421775Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm ACK delay=45943 blocks=[4..6, 8..8] ecn_counts=None [2025-09-12T02:38:36.109436432Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm STREAM id=7 off=0 len=1 fin=false [2025-09-12T02:38:36.109496344Z TRACE quiche_apps::client] 0.0.0.0:51077: processed 54 bytes [2025-09-12T02:38:36.109506153Z TRACE quiche_apps::client] 0.0.0.0:51077: recv() would block [2025-09-12T02:38:36.109511513Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:36.109518256Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 stream id 7 is readable [2025-09-12T02:38:36.109531029Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 7 [2025-09-12T02:38:36.109564643Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=9 pn=10 [2025-09-12T02:38:36.109571185Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm ACK delay=20 blocks=[5..5, 7..8, 11..11] ecn_counts=None [2025-09-12T02:38:36.109588397Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=649.243342ms latest_rtt=0ns srtt=907.810006ms min_rtt=0ns rttvar=648.435718ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=111 app_limited=true congestion_recovery_start_time=None Rate { delivered: 832, delivered_time: Instant { tv_sec: 702, tv_nsec: 609576720 }, first_sent_time: Instant { tv_sec: 702, tv_nsec: 617435466 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 2.078445716s, delivered: 499, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 701, tv_nsec: 576486900 }), send_elapsed: 2.078445716s, ack_elapsed: 1.03308982s, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 18588, last_update: Instant { tv_sec: 702, tv_nsec: 610027836 }, next_time: Instant { tv_sec: 702, tv_nsec: 980913374 }, 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-09-12T02:38:36.109665541Z TRACE quiche_apps::client] written 47 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:36.109675700Z TRACE quiche_apps::client] 0.0.0.0:51077 -> 193.167.100.100:443: done writing [2025-09-12T02:38:36.760391341Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:36.760414985Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 loss detection timeout expired [2025-09-12T02:38:36.760422690Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 packet 7 lost on epoch 2 [2025-09-12T02:38:36.760438970Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=none latest_rtt=0ns srtt=907.810006ms min_rtt=0ns rttvar=648.435718ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 703, tv_nsec: 628067855 }) Rate { delivered: 832, delivered_time: Instant { tv_sec: 702, tv_nsec: 609576720 }, first_sent_time: Instant { tv_sec: 702, tv_nsec: 617435466 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 2.078445716s, delivered: 499, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 701, tv_nsec: 576486900 }), send_elapsed: 2.078445716s, ack_elapsed: 1.03308982s, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 18588, last_update: Instant { tv_sec: 702, tv_nsec: 610027836 }, next_time: Instant { tv_sec: 702, tv_nsec: 980913374 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:36.760484295Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:36.760513600Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=73 pn=11 [2025-09-12T02:38:36.760518369Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:38:36.760535621Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=3.526513424s latest_rtt=0ns srtt=907.810006ms min_rtt=0ns rttvar=648.435718ms cwnd=9450 ssthresh=9450 bytes_in_flight=111 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 704, tv_nsec: 678732517 }) Rate { delivered: 832, delivered_time: Instant { tv_sec: 702, tv_nsec: 980913374 }, first_sent_time: Instant { tv_sec: 702, tv_nsec: 980913374 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 8, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 2.078445716s, delivered: 499, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 701, tv_nsec: 576486900 }), send_elapsed: 2.078445716s, ack_elapsed: 1.03308982s, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 13012, last_update: Instant { tv_sec: 702, tv_nsec: 610027836 }, next_time: Instant { tv_sec: 702, tv_nsec: 980913374 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:36.760598228Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:36.760605602Z TRACE quiche_apps::client] 0.0.0.0:51077 -> 193.167.100.100:443: done writing [2025-09-12T02:38:36.792600110Z TRACE quiche_apps::client] got 1133 bytes from 193.167.100.100:443 to 0.0.0.0:51077 [2025-09-12T02:38:36.792622302Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx pkt Short dcid=f329f7bd428caad4886893139fa3623d43b02a06 key_phase=false len=1112 pn=14 [2025-09-12T02:38:36.792640546Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm ACK delay=43 blocks=[4..6, 8..8, 11..11] ecn_counts=None [2025-09-12T02:38:36.792651957Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 packet newly acked 11 [2025-09-12T02:38:36.792662457Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 rx frm STREAM id=0 off=0 len=1081 fin=true [2025-09-12T02:38:36.792690950Z TRACE quiche_apps::client] 0.0.0.0:51077: processed 1133 bytes [2025-09-12T02:38:36.792700238Z TRACE quiche_apps::client] 0.0.0.0:51077: recv() would block [2025-09-12T02:38:36.792704065Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:36.792709896Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 stream id 0 is readable [2025-09-12T02:38:36.792731797Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792739702Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 7 bytes on stream 0 [2025-09-12T02:38:36.792745973Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792751333Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 rx frm UNKNOWN raw_type=3315304315154172093 stream=0 payload_len=0 [2025-09-12T02:38:36.792763126Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792770179Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 7 bytes on stream 0 [2025-09-12T02:38:36.792777192Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792784806Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 18 bytes on stream 0 [2025-09-12T02:38:36.792789555Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 rx frm UNKNOWN raw_type=3170367685187982819 stream=0 payload_len=18 [2025-09-12T02:38:36.792799003Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792805014Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792809101Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 16 bytes on stream 0 [2025-09-12T02:38:36.792812328Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 rx frm HEADERS stream=0 payload_len=16 [2025-09-12T02:38:36.792816636Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0 [2025-09-12T02:38:36.792820012Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true [2025-09-12T02:38:36.792825142Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[113, 117, 105, 99, 104, 101] [2025-09-12T02:38:36.792830592Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[49, 48, 50, 52] [2025-09-12T02:38:36.792839058Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "quiche"), ("content-length", "1024")] on stream id 0 [2025-09-12T02:38:36.792845189Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 stream id 0 is readable [2025-09-12T02:38:36.792849277Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792853384Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792857272Z TRACE quiche::h3::stream] f329f7bd428caad4886893139fa3623d43b02a06 read 1 bytes on stream 0 [2025-09-12T02:38:36.792860408Z TRACE quiche::h3] f329f7bd428caad4886893139fa3623d43b02a06 rx frm DATA stream=0 wire_payload_len=1024 [2025-09-12T02:38:36.792869094Z DEBUG quiche_apps::common] got 1024 bytes of response data on stream 0 [2025-09-12T02:38:36.792872901Z DEBUG quiche_apps::common] 1/1 responses received [2025-09-12T02:38:36.792876167Z INFO quiche_apps::common] 1/1 response(s) received in 3.12139672s, closing... [2025-09-12T02:38:36.792888951Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx pkt Short dcid=a69482df0e88cab0c84feeb56af981402e599cb8 key_phase=false len=11 pn=12 [2025-09-12T02:38:36.792892858Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-09-12T02:38:36.792903719Z TRACE quiche::recovery::congestion::recovery] f329f7bd428caad4886893139fa3623d43b02a06 timer=309.467315ms latest_rtt=679.358461ms srtt=879.210562ms min_rtt=0ns rttvar=543.525674ms cwnd=9450 ssthresh=9450 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 704, tv_nsec: 711118589 }) Rate { delivered: 943, delivered_time: Instant { tv_sec: 703, tv_nsec: 660536371 }, first_sent_time: Instant { tv_sec: 703, tv_nsec: 660536371 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 11, rate_sample: RateSample { bandwidth: 2.56 Kbps, is_app_limited: true, interval: 679.358461ms, delivered: 111, prior_delivered: 832, prior_time: Some(Instant { tv_sec: 702, tv_nsec: 980913374 }), send_elapsed: 0ns, ack_elapsed: 679.358461ms, rtt: 679.358461ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 13435, last_update: Instant { tv_sec: 703, tv_nsec: 660536371 }, next_time: Instant { tv_sec: 703, tv_nsec: 660536371 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:36.792963290Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:51077 to 193.167.100.100:443 [2025-09-12T02:38:36.792968851Z TRACE quiche_apps::client] 0.0.0.0:51077 -> 193.167.100.100:443: done writing [2025-09-12T02:38:45.956438571Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:45.956480419Z TRACE quiche] f329f7bd428caad4886893139fa3623d43b02a06 draining timeout expired [2025-09-12T02:38:45.956536244Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:45.956545782Z INFO quiche_apps::client] connection closed, recv=8 sent=13 lost=2 retrans=2 sent_bytes=1711 recv_bytes=3056 lost_bytes=111 [local_addr=0.0.0.0:51077 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=8 sent=13 lost=2 retrans=2 rtt=879.210562ms min_rtt=Some(0ns) rttvar=543.525674ms cwnd=9450 sent_bytes=1711 recv_bytes=3056 lost_bytes=0 stream_retrans_bytes=334 pmtu=1350 delivery_rate=320] [2025-09-12T02:38:45.961421561Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-09-12T02:38:45.961668343Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:41243 with scid ee4fa5d5eebc3ae8057948f596a4a3f93f285203 [2025-09-12T02:38:45.961712095Z TRACE quiche::tls] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 write message lvl=Initial len=266 [2025-09-12T02:38:45.961729107Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Initial version=1 dcid=b2fed66efcd4e2d82aac91dd8775ba68 scid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 len=270 pn=0 [2025-09-12T02:38:45.961733365Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm CRYPTO off=0 len=266 [2025-09-12T02:38:45.961747622Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=998.925601ms 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: 712, tv_nsec: 829330240 }, first_sent_time: Instant { tv_sec: 712, tv_nsec: 829330240 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 712, tv_nsec: 829330240 }, next_time: Instant { tv_sec: 712, tv_nsec: 829330240 }, 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-09-12T02:38:45.961829094Z TRACE quiche_apps::client] written 1200 [2025-09-12T02:38:46.961894537Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:46.961920366Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 loss detection timeout expired [2025-09-12T02:38:46.961929383Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=997.745753ms 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: 712, tv_nsec: 829330240 }, first_sent_time: Instant { tv_sec: 712, tv_nsec: 829330240 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 712, tv_nsec: 829330240 }, next_time: Instant { tv_sec: 712, tv_nsec: 829330240 }, 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-09-12T02:38:46.961966903Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:46.962006868Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Initial version=1 dcid=b2fed66efcd4e2d82aac91dd8775ba68 scid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 len=270 pn=1 [2025-09-12T02:38:46.962011346Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm CRYPTO off=0 len=266 [2025-09-12T02:38:46.962031093Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=1.997950447s 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: 712, tv_nsec: 829330240 }, first_sent_time: Instant { tv_sec: 712, tv_nsec: 829330240 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 713, tv_nsec: 829635833 }, next_time: Instant { tv_sec: 713, tv_nsec: 829635833 }, 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-09-12T02:38:46.962096906Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:46.962104440Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:46.995322571Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:41243 [2025-09-12T02:38:46.995345003Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Initial version=1 dcid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 scid=8761b867c03f3a123bfaedf963efc20cda68a4b2 token= len=116 pn=0 [2025-09-12T02:38:46.995391691Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm ACK delay=51 blocks=[1..1] ecn_counts=None [2025-09-12T02:38:46.995401569Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet newly acked 1 [2025-09-12T02:38:46.995408873Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet 0 lost on epoch 0 [2025-09-12T02:38:46.995432196Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm CRYPTO off=0 len=90 [2025-09-12T02:38:46.995584913Z TRACE quiche::tls] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 set write secret lvl=Handshake [2025-09-12T02:38:46.995604620Z TRACE quiche::tls] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 set read secret lvl=Handshake [2025-09-12T02:38:46.995654303Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Handshake version=1 dcid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 scid=8761b867c03f3a123bfaedf963efc20cda68a4b2 len=719 pn=1 [2025-09-12T02:38:46.995664422Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm CRYPTO off=0 len=698 [2025-09-12T02:38:46.995859968Z TRACE quiche::tls] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 write message lvl=Handshake len=36 [2025-09-12T02:38:46.995863695Z TRACE quiche::tls] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 set write secret lvl=OneRTT [2025-09-12T02:38:46.995870358Z TRACE quiche::tls] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 set read secret lvl=OneRTT [2025-09-12T02:38:46.995886298Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 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(b2fed66efcd4e2d82aac91dd8775ba68), 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(8761b867c03f3a123bfaedf963efc20cda68a4b2), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-09-12T02:38:46.995907818Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Short dcid=0000000000000000000000000000000000000000 key_phase=true len=245 pn=4066594126 [2025-09-12T02:38:46.995913008Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 dropped invalid packet [2025-09-12T02:38:46.995915653Z TRACE quiche_apps::client] 0.0.0.0:41243: processed 1200 bytes [2025-09-12T02:38:46.995920071Z TRACE quiche_apps::client] 0.0.0.0:41243: recv() would block [2025-09-12T02:38:46.995922586Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:46.995937483Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm SETTINGS stream=2 len=18 [2025-09-12T02:38:46.995950889Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 open GREASE stream 14 [2025-09-12T02:38:46.995958643Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm GREASE stream=0 len=0 [2025-09-12T02:38:46.995964093Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm GREASE stream=0 len=18 [2025-09-12T02:38:46.995971217Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm HEADERS stream=0 len=30 fin=true [2025-09-12T02:38:46.995977108Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /pjoylspbww", "user-agent: quiche"] [2025-09-12T02:38:46.996036489Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Initial version=1 dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 scid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 len=6 pn=2 [2025-09-12T02:38:46.996040196Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=86 blocks=[0..0] ecn_counts=None [2025-09-12T02:38:46.996048682Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=99.365949ms latest_rtt=33.358203ms srtt=33.358203ms min_rtt=33.358203ms rttvar=16.679101ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 862994036 }) Rate { delivered: 333, delivered_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { bandwidth: 2.58 Kbps, is_app_limited: true, interval: 1.033663796s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 712, tv_nsec: 829330240 }), send_elapsed: 1.000305593s, ack_elapsed: 1.033663796s, rtt: 33.358203ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354110, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:46.996075101Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Handshake version=1 dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 scid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 len=45 pn=3 [2025-09-12T02:38:46.996078157Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=52 blocks=[1..1] ecn_counts=None [2025-09-12T02:38:46.996080812Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm CRYPTO off=0 len=36 [2025-09-12T02:38:46.996086934Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=100.015757ms latest_rtt=33.358203ms srtt=33.358203ms min_rtt=33.358203ms rttvar=16.679101ms cwnd=9450 ssthresh=9450 bytes_in_flight=111 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 333, delivered_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { bandwidth: 2.58 Kbps, is_app_limited: true, interval: 1.033663796s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 712, tv_nsec: 829330240 }), send_elapsed: 1.000305593s, ack_elapsed: 1.033663796s, rtt: 33.358203ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354110, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:46.996100299Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 dropped epoch 0 state [2025-09-12T02:38:46.996113844Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=1128 pn=4 [2025-09-12T02:38:46.996116579Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e5, b8, e1, fc, 05, 0a, c0, 27, f5, 6a, 0a, 0d, 29, 17, 7a, 66, 6e, 24, 28, 82] reset_token=[04, 4c, 69, 0f, 07, 27, 06, 82, fe, 01, 0c, 40, f7, 44, a7, 16] [2025-09-12T02:38:46.996123973Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[59, 9a, bb, 5c, 57, ab, 4f, 7a, 8e, ea, 47, a7, bf, 99, 42, 15, 38, 39, 7a, 9e] reset_token=[57, 45, bf, b6, c2, da, 00, da, bd, a8, 8f, d0, 77, 7f, c2, 0e] [2025-09-12T02:38:46.996129784Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[3c, ba, 9a, 17, e2, 4d, fb, ed, 7c, 57, 53, 41, 35, 8c, 70, 0c, ee, 50, ce, 2a] reset_token=[1d, 11, 36, d2, 9e, 27, c6, c5, ec, 20, f5, 88, 5c, b1, 0b, 68] [2025-09-12T02:38:46.996135515Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[c2, 55, 16, a7, 7b, 95, ce, ab, 48, a3, 27, e7, 39, ce, d9, ea, fa, 11, f9, ea] reset_token=[1d, de, c4, b5, 0d, 9a, 8e, 50, 9f, e2, ce, ff, fd, 1f, ed, 32] [2025-09-12T02:38:46.996141055Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[34, 9d, 1e, bf, a6, 9c, 13, 17, db, 78, a5, 5b, a8, 62, 2e, 62, 30, 50, e0, ba] reset_token=[94, 7b, 5a, 96, 7b, dd, 3a, 96, 96, d0, 8d, 50, ae, be, f8, 20] [2025-09-12T02:38:46.996149821Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[95, a5, 33, 3a, 52, e2, ba, 75, 55, b4, a1, 14, 55, 88, 26, 7b, 97, be, 3a, 0a] reset_token=[b6, 62, 35, 42, 89, f3, 39, 29, dc, e7, 79, 45, 41, c4, 34, 79] [2025-09-12T02:38:46.996155462Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[44, dc, d2, ea, 0c, 05, ff, d5, c5, d8, c9, 09, 70, a4, 45, 9d, f0, 8b, a0, 9b] reset_token=[8a, 2c, 7a, bb, f0, 51, 2a, fa, 64, c5, aa, 86, 2c, 13, 5f, 80] [2025-09-12T02:38:46.996161082Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=2 off=0 len=19 fin=false [2025-09-12T02:38:46.996163738Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm PADDING len=824 [2025-09-12T02:38:46.996190047Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=99.912513ms latest_rtt=33.358203ms srtt=33.358203ms min_rtt=33.358203ms rttvar=16.679101ms cwnd=9450 ssthresh=9450 bytes_in_flight=1277 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 333, delivered_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { bandwidth: 2.58 Kbps, is_app_limited: true, interval: 1.033663796s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 712, tv_nsec: 829330240 }), send_elapsed: 1.000305593s, ack_elapsed: 1.033663796s, rtt: 33.358203ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354110, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:46.996227527Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:46.996235993Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=6 pn=5 [2025-09-12T02:38:46.996238698Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=6 off=0 len=1 fin=false [2025-09-12T02:38:46.996247364Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=99.854925ms latest_rtt=33.358203ms srtt=33.358203ms min_rtt=33.358203ms rttvar=16.679101ms cwnd=9450 ssthresh=9450 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 333, delivered_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { bandwidth: 2.58 Kbps, is_app_limited: true, interval: 1.033663796s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 712, tv_nsec: 829330240 }), send_elapsed: 1.000305593s, ack_elapsed: 1.033663796s, rtt: 33.358203ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354110, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:46.996298340Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:46.996312076Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=6 pn=6 [2025-09-12T02:38:46.996316935Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=10 off=0 len=1 fin=false [2025-09-12T02:38:46.996326543Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=99.775707ms latest_rtt=33.358203ms srtt=33.358203ms min_rtt=33.358203ms rttvar=16.679101ms cwnd=9450 ssthresh=9450 bytes_in_flight=1365 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 333, delivered_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { bandwidth: 2.58 Kbps, is_app_limited: true, interval: 1.033663796s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 712, tv_nsec: 829330240 }), send_elapsed: 1.000305593s, ack_elapsed: 1.033663796s, rtt: 33.358203ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354110, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:46.996382407Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:46.996397506Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=73 pn=7 [2025-09-12T02:38:46.996402305Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:38:46.996412123Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=99.690166ms latest_rtt=33.358203ms srtt=33.358203ms min_rtt=33.358203ms rttvar=16.679101ms cwnd=9450 ssthresh=9450 bytes_in_flight=1476 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 333, delivered_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { bandwidth: 2.58 Kbps, is_app_limited: true, interval: 1.033663796s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 712, tv_nsec: 829330240 }), send_elapsed: 1.000305593s, ack_elapsed: 1.033663796s, rtt: 33.358203ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354110, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:46.996450966Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:46.996460904Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=31 pn=8 [2025-09-12T02:38:46.996465413Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=14 off=0 len=26 fin=true [2025-09-12T02:38:46.996482455Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=99.619714ms latest_rtt=33.358203ms srtt=33.358203ms min_rtt=33.358203ms rttvar=16.679101ms cwnd=9450 ssthresh=9450 bytes_in_flight=1545 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 333, delivered_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { bandwidth: 2.58 Kbps, is_app_limited: true, interval: 1.033663796s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 712, tv_nsec: 829330240 }), send_elapsed: 1.000305593s, ack_elapsed: 1.033663796s, rtt: 33.358203ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354110, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:46.996520596Z TRACE quiche_apps::client] written 69 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:46.996525405Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:47.029081142Z TRACE quiche_apps::client] got 748 bytes from 193.167.100.100:443 to 0.0.0.0:41243 [2025-09-12T02:38:47.029096341Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Short dcid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 key_phase=false len=727 pn=2 [2025-09-12T02:38:47.029106490Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm ACK delay=4 blocks=[4..6] ecn_counts=None [2025-09-12T02:38:47.029112361Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet newly acked 4 [2025-09-12T02:38:47.029116889Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet newly acked 5 [2025-09-12T02:38:47.029121007Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet newly acked 6 [2025-09-12T02:38:47.029129914Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[4a, 16, 8e, 63, 55, 33, 97, 58, 61, e9, 16, 05, 83, e8, d7, 92, c6, d5, fa, f7] reset_token=[f0, 8f, 18, 22, 08, df, 0b, b4, 91, c9, f8, 4a, b2, f0, e5, 54] [2025-09-12T02:38:47.029141165Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[3f, a2, 47, 4c, 27, 3c, 79, 22, c6, 91, 42, 14, e1, 85, 4c, a8, 81, fd, 6f, f4] reset_token=[2e, 4a, 91, 69, 63, b3, fb, d6, ce, 51, b5, 89, b5, 4f, 68, 3e] [2025-09-12T02:38:47.029151073Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[c8, c5, 11, 1a, 22, 49, fd, 36, fb, fc, 43, 44, 1d, 9f, a3, e3, 86, ee, c0, 2e] reset_token=[96, 45, 36, fc, 41, 47, 99, 38, e6, cd, a6, a1, f7, 64, 7b, 02] [2025-09-12T02:38:47.029160952Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[e0, d5, 32, ab, 2c, d1, 6d, 73, 4e, ed, 10, 19, b1, 84, 94, c7, 6b, 8c, 2a, 4a] reset_token=[91, ac, d0, 6c, a5, 12, 72, d0, 38, a5, 01, ed, 4c, 0a, 75, a1] [2025-09-12T02:38:47.029170880Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[66, a6, 3c, 6e, e4, 0f, 86, f8, 4f, b6, 88, 38, d9, fe, 3c, c2, e9, 35, 8d, 5c] reset_token=[e3, fa, 0b, 38, bd, 75, 80, 4d, d1, 11, 68, fa, c2, f3, 0a, 83] [2025-09-12T02:38:47.029180559Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[42, 1d, 43, f2, 94, de, 37, 28, 74, fd, ea, f5, 3a, 3c, 67, 99, 51, 96, f4, 72] reset_token=[0b, f1, 81, 59, 3b, ee, 9f, 58, 6a, ee, 70, 5a, 13, c0, c4, af] [2025-09-12T02:38:47.029197921Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[6c, 6b, 1b, 8a, bd, 08, e4, f7, aa, f6, b9, 3d, 6d, b0, 3d, bd, 43, d0, 7c, 7d] reset_token=[db, a0, 54, e4, 15, 58, 97, c9, b6, 68, 0f, f5, c0, a8, 11, b0] [2025-09-12T02:38:47.029206167Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm HANDSHAKE_DONE [2025-09-12T02:38:47.029212208Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 dropped epoch 1 state [2025-09-12T02:38:47.029216516Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm CRYPTO off=0 len=396 [2025-09-12T02:38:47.029243176Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm STREAM id=3 off=0 len=19 fin=false [2025-09-12T02:38:47.029268323Z TRACE quiche_apps::client] 0.0.0.0:41243: processed 748 bytes [2025-09-12T02:38:47.029276448Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:41243 [2025-09-12T02:38:47.029282189Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Short dcid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 key_phase=false len=23 pn=3 [2025-09-12T02:38:47.029290034Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm STREAM id=7 off=0 len=1 fin=false [2025-09-12T02:38:47.029306535Z TRACE quiche_apps::client] 0.0.0.0:41243: processed 44 bytes [2025-09-12T02:38:47.029313628Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:41243 [2025-09-12T02:38:47.029319299Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Short dcid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 key_phase=false len=23 pn=4 [2025-09-12T02:38:47.029326502Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm STREAM id=11 off=0 len=1 fin=false [2025-09-12T02:38:47.029336270Z TRACE quiche_apps::client] 0.0.0.0:41243: processed 44 bytes [2025-09-12T02:38:47.029342813Z TRACE quiche_apps::client] got 69 bytes from 193.167.100.100:443 to 0.0.0.0:41243 [2025-09-12T02:38:47.029364033Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Short dcid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 key_phase=false len=48 pn=5 [2025-09-12T02:38:47.029374953Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm STREAM id=15 off=0 len=26 fin=true [2025-09-12T02:38:47.029383830Z TRACE quiche_apps::client] 0.0.0.0:41243: processed 69 bytes [2025-09-12T02:38:47.029389270Z TRACE quiche_apps::client] 0.0.0.0:41243: recv() would block [2025-09-12T02:38:47.029393157Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:47.029397555Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 stream id 3 is readable [2025-09-12T02:38:47.029404709Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 3 [2025-09-12T02:38:47.029409187Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 open peer's control stream 3 [2025-09-12T02:38:47.029412564Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 3 [2025-09-12T02:38:47.029415729Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 3 [2025-09-12T02:38:47.029419436Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 16 bytes on stream 3 [2025-09-12T02:38:47.029422382Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(850739119576329056, 381196582866752036)]), additional_settings=Some([(850739119576329056, 381196582866752036)]) stream=3 payload_len=16 [2025-09-12T02:38:47.029429595Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 stream id 7 is readable [2025-09-12T02:38:47.029433012Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 7 [2025-09-12T02:38:47.029453420Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 stream id 11 is readable [2025-09-12T02:38:47.029457368Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 11 [2025-09-12T02:38:47.029460574Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 stream id 15 is readable [2025-09-12T02:38:47.029463629Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 15 [2025-09-12T02:38:47.029466625Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 7 bytes on stream 15 [2025-09-12T02:38:47.029477305Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=5 pn=9 [2025-09-12T02:38:47.029480150Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=14 blocks=[2..5] ecn_counts=None [2025-09-12T02:38:47.029487213Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=75.618203ms latest_rtt=33.065913ms srtt=33.321666ms min_rtt=33.065913ms rttvar=12.582397ms cwnd=9450 ssthresh=9450 bytes_in_flight=180 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 1587, delivered_time: Instant { tv_sec: 713, tv_nsec: 896747858 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 6, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 33.065913ms, delivered: 1254, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 863681945 }), send_elapsed: 0ns, ack_elapsed: 33.065913ms, rtt: 33.065913ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354499, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:47.029536255Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:47.029541385Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:47.105672665Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:47.105692542Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 loss detection timeout expired [2025-09-12T02:38:47.105698824Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=108.056875ms latest_rtt=33.065913ms srtt=33.321666ms min_rtt=33.065913ms rttvar=12.582397ms cwnd=9450 ssthresh=9450 bytes_in_flight=180 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 1587, delivered_time: Instant { tv_sec: 713, tv_nsec: 896747858 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 6, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 33.065913ms, delivered: 1254, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 863681945 }), send_elapsed: 0ns, ack_elapsed: 33.065913ms, rtt: 33.065913ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354499, last_update: Instant { tv_sec: 713, tv_nsec: 863681945 }, next_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:47.105732517Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:47.105754658Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=79 pn=10 [2025-09-12T02:38:47.105759397Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=9548 blocks=[2..5] ecn_counts=None [2025-09-12T02:38:47.105764817Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:38:47.105779916Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=217.263515ms latest_rtt=33.065913ms srtt=33.321666ms min_rtt=33.065913ms rttvar=12.582397ms cwnd=9450 ssthresh=9450 bytes_in_flight=297 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 1587, delivered_time: Instant { tv_sec: 713, tv_nsec: 896747858 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 6, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 33.065913ms, delivered: 1254, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 863681945 }), send_elapsed: 0ns, ack_elapsed: 33.065913ms, rtt: 33.065913ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354499, last_update: Instant { tv_sec: 713, tv_nsec: 973395005 }, next_time: Instant { tv_sec: 713, tv_nsec: 973395005 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:47.105839888Z TRACE quiche_apps::client] written 117 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:47.105846360Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:47.324116366Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:47.324132737Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 loss detection timeout expired [2025-09-12T02:38:47.324138908Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=216.206329ms latest_rtt=33.065913ms srtt=33.321666ms min_rtt=33.065913ms rttvar=12.582397ms cwnd=9450 ssthresh=9450 bytes_in_flight=297 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 1587, delivered_time: Instant { tv_sec: 713, tv_nsec: 896747858 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 6, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 33.065913ms, delivered: 1254, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 863681945 }), send_elapsed: 0ns, ack_elapsed: 33.065913ms, rtt: 33.065913ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354499, last_update: Instant { tv_sec: 713, tv_nsec: 973395005 }, next_time: Instant { tv_sec: 713, tv_nsec: 973395005 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:47.324161531Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:47.324183492Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=81 pn=11 [2025-09-12T02:38:47.324187850Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=36851 blocks=[2..5] ecn_counts=None [2025-09-12T02:38:47.324201265Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:38:47.324216774Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=434.557567ms latest_rtt=33.065913ms srtt=33.321666ms min_rtt=33.065913ms rttvar=12.582397ms cwnd=9450 ssthresh=9450 bytes_in_flight=416 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 1587, delivered_time: Instant { tv_sec: 713, tv_nsec: 896747858 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 6, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 33.065913ms, delivered: 1254, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 863681945 }), send_elapsed: 0ns, ack_elapsed: 33.065913ms, rtt: 33.065913ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354499, last_update: Instant { tv_sec: 714, tv_nsec: 191823387 }, next_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:47.324268752Z TRACE quiche_apps::client] written 119 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:47.324279071Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=39 pn=12 [2025-09-12T02:38:47.324282577Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=36864 blocks=[2..5] ecn_counts=None [2025-09-12T02:38:47.324286054Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=14 off=0 len=26 fin=true [2025-09-12T02:38:47.324293228Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=434.58542ms latest_rtt=33.065913ms srtt=33.321666ms min_rtt=33.065913ms rttvar=12.582397ms cwnd=9450 ssthresh=9450 bytes_in_flight=493 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 713, tv_nsec: 897040148 }) Rate { delivered: 1587, delivered_time: Instant { tv_sec: 713, tv_nsec: 896747858 }, first_sent_time: Instant { tv_sec: 713, tv_nsec: 863681945 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 6, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 33.065913ms, delivered: 1254, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 863681945 }), send_elapsed: 0ns, ack_elapsed: 33.065913ms, rtt: 33.065913ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 354499, last_update: Instant { tv_sec: 714, tv_nsec: 191823387 }, next_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:38:47.324336078Z TRACE quiche_apps::client] written 77 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:47.324344473Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:47.355165346Z TRACE quiche_apps::client] got 45 bytes from 193.167.100.100:443 to 0.0.0.0:41243 [2025-09-12T02:38:47.355187187Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Short dcid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 key_phase=false len=24 pn=6 [2025-09-12T02:38:47.355203187Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm ACK delay=14 blocks=[9..9, 12..12] ecn_counts=None [2025-09-12T02:38:47.355224026Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet newly acked 9 [2025-09-12T02:38:47.355230067Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet newly acked 12 [2025-09-12T02:38:47.355236830Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet 7 lost on epoch 2 [2025-09-12T02:38:47.355242290Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet 8 lost on epoch 2 [2025-09-12T02:38:47.355247590Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet 10 lost on epoch 2 [2025-09-12T02:38:47.355274390Z TRACE quiche_apps::client] 0.0.0.0:41243: processed 45 bytes [2025-09-12T02:38:47.355283798Z TRACE quiche_apps::client] 0.0.0.0:41243: recv() would block [2025-09-12T02:38:47.355289348Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:47.355311379Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=78 pn=13 [2025-09-12T02:38:47.355318162Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=15 blocks=[6..6] ecn_counts=None [2025-09-12T02:38:47.355324323Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:38:47.355338961Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=5.99205ms latest_rtt=31.013925ms srtt=33.033197ms min_rtt=31.013925ms rttvar=10.013732ms cwnd=6615 ssthresh=6615 bytes_in_flight=235 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 714, tv_nsec: 222837312 }) Rate { delivered: 1664, delivered_time: Instant { tv_sec: 714, tv_nsec: 222837312 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 12, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 328.141442ms, delivered: 77, prior_delivered: 1587, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 896747858 }), send_elapsed: 328.141442ms, ack_elapsed: 326.089454ms, rtt: 31.013925ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 250316, last_update: Instant { tv_sec: 714, tv_nsec: 191823387 }, next_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, 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=1.3794620881905602 w_max=8032.5 } [2025-09-12T02:38:47.355425693Z TRACE quiche_apps::client] written 116 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:47.355431154Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:47.361531098Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:47.361544984Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 loss detection timeout expired [2025-09-12T02:38:47.361550103Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet 11 lost on epoch 2 [2025-09-12T02:38:47.361554722Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=91.831778ms latest_rtt=31.013925ms srtt=33.033197ms min_rtt=31.013925ms rttvar=10.013732ms cwnd=6615 ssthresh=6615 bytes_in_flight=116 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 714, tv_nsec: 222837312 }) Rate { delivered: 1664, delivered_time: Instant { tv_sec: 714, tv_nsec: 222837312 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 12, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 328.141442ms, delivered: 77, prior_delivered: 1587, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 896747858 }), send_elapsed: 328.141442ms, ack_elapsed: 326.089454ms, rtt: 31.013925ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 250316, last_update: Instant { tv_sec: 714, tv_nsec: 191823387 }, next_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, 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=1.3794620881905602 w_max=8032.5 } [2025-09-12T02:38:47.361582464Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:47.361599285Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=79 pn=14 [2025-09-12T02:38:47.361603663Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=801 blocks=[6..6] ecn_counts=None [2025-09-12T02:38:47.361608342Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:38:47.361619062Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=98.058188ms latest_rtt=31.013925ms srtt=33.033197ms min_rtt=31.013925ms rttvar=10.013732ms cwnd=6615 ssthresh=6615 bytes_in_flight=233 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 714, tv_nsec: 222837312 }) Rate { delivered: 1664, delivered_time: Instant { tv_sec: 714, tv_nsec: 222837312 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 12, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 328.141442ms, delivered: 77, prior_delivered: 1587, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 896747858 }), send_elapsed: 328.141442ms, ack_elapsed: 326.089454ms, rtt: 31.013925ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 250316, last_update: Instant { tv_sec: 714, tv_nsec: 191823387 }, next_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, 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=1.3794620881905602 w_max=8032.5 } [2025-09-12T02:38:47.361666100Z TRACE quiche_apps::client] written 117 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:47.361672462Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:47.459821245Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:47.459835202Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 loss detection timeout expired [2025-09-12T02:38:47.459840712Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=97.924303ms latest_rtt=31.013925ms srtt=33.033197ms min_rtt=31.013925ms rttvar=10.013732ms cwnd=6615 ssthresh=6615 bytes_in_flight=233 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 714, tv_nsec: 222837312 }) Rate { delivered: 1664, delivered_time: Instant { tv_sec: 714, tv_nsec: 222837312 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 12, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 328.141442ms, delivered: 77, prior_delivered: 1587, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 896747858 }), send_elapsed: 328.141442ms, ack_elapsed: 326.089454ms, rtt: 31.013925ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 250316, last_update: Instant { tv_sec: 714, tv_nsec: 191823387 }, next_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, 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=1.3794620881905602 w_max=8032.5 } [2025-09-12T02:38:47.459867292Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:47.459883682Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=79 pn=15 [2025-09-12T02:38:47.459888021Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm ACK delay=13086 blocks=[6..6] ecn_counts=None [2025-09-12T02:38:47.459892389Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:38:47.459904952Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=196.144812ms latest_rtt=31.013925ms srtt=33.033197ms min_rtt=31.013925ms rttvar=10.013732ms cwnd=6615 ssthresh=6615 bytes_in_flight=350 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 714, tv_nsec: 222837312 }) Rate { delivered: 1664, delivered_time: Instant { tv_sec: 714, tv_nsec: 222837312 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 191823387 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 12, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 328.141442ms, delivered: 77, prior_delivered: 1587, prior_time: Some(Instant { tv_sec: 713, tv_nsec: 896747858 }), send_elapsed: 328.141442ms, ack_elapsed: 326.089454ms, rtt: 31.013925ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 250316, last_update: Instant { tv_sec: 714, tv_nsec: 327527516 }, next_time: Instant { tv_sec: 714, tv_nsec: 327527516 }, 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=1.3794620881905602 w_max=8032.5 } [2025-09-12T02:38:47.459950467Z TRACE quiche_apps::client] written 117 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:47.459956208Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:47.491740778Z TRACE quiche_apps::client] got 1129 bytes from 193.167.100.100:443 to 0.0.0.0:41243 [2025-09-12T02:38:47.491756257Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx pkt Short dcid=ee4fa5d5eebc3ae8057948f596a4a3f93f285203 key_phase=false len=1108 pn=9 [2025-09-12T02:38:47.491767939Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm ACK delay=31 blocks=[14..15] ecn_counts=None [2025-09-12T02:38:47.491774622Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet newly acked 14 [2025-09-12T02:38:47.491779100Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet newly acked 15 [2025-09-12T02:38:47.491784650Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 packet 13 lost on epoch 2 [2025-09-12T02:38:47.491791182Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm STREAM id=0 off=0 len=1081 fin=true [2025-09-12T02:38:47.491809537Z TRACE quiche_apps::client] 0.0.0.0:41243: processed 1129 bytes [2025-09-12T02:38:47.491816069Z TRACE quiche_apps::client] 0.0.0.0:41243: recv() would block [2025-09-12T02:38:47.491819786Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:47.491822641Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 stream id 0 is readable [2025-09-12T02:38:47.491827441Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491833021Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 7 bytes on stream 0 [2025-09-12T02:38:47.491846296Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491850744Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm UNKNOWN raw_type=1397083532740221339 stream=0 payload_len=0 [2025-09-12T02:38:47.491859881Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491865261Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 7 bytes on stream 0 [2025-09-12T02:38:47.491870301Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491875831Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 18 bytes on stream 0 [2025-09-12T02:38:47.491880039Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm UNKNOWN raw_type=166126947752843453 stream=0 payload_len=18 [2025-09-12T02:38:47.491886741Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491891911Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491897001Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 16 bytes on stream 0 [2025-09-12T02:38:47.491901028Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm HEADERS stream=0 payload_len=16 [2025-09-12T02:38:47.491905827Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0 [2025-09-12T02:38:47.491909735Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true [2025-09-12T02:38:47.491916177Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[113, 117, 105, 99, 104, 101] [2025-09-12T02:38:47.491921106Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[49, 48, 50, 52] [2025-09-12T02:38:47.491927748Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "quiche"), ("content-length", "1024")] on stream id 0 [2025-09-12T02:38:47.491932818Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 stream id 0 is readable [2025-09-12T02:38:47.491935964Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491938959Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491941925Z TRACE quiche::h3::stream] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 read 1 bytes on stream 0 [2025-09-12T02:38:47.491944299Z TRACE quiche::h3] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 rx frm DATA stream=0 wire_payload_len=1024 [2025-09-12T02:38:47.491951242Z DEBUG quiche_apps::common] got 1024 bytes of response data on stream 0 [2025-09-12T02:38:47.491954458Z DEBUG quiche_apps::common] 1/1 responses received [2025-09-12T02:38:47.491956963Z INFO quiche_apps::common] 1/1 response(s) received in 1.530122759s, closing... [2025-09-12T02:38:47.491966471Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx pkt Short dcid=8761b867c03f3a123bfaedf963efc20cda68a4b2 key_phase=false len=11 pn=16 [2025-09-12T02:38:47.491969316Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-09-12T02:38:47.491977261Z TRACE quiche::recovery::congestion::recovery] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 timer=89.284597ms latest_rtt=31.880138ms srtt=32.858064ms min_rtt=31.013925ms rttvar=7.860563ms cwnd=6615 ssthresh=6615 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 714, tv_nsec: 254925300 }) Rate { delivered: 1898, delivered_time: Instant { tv_sec: 714, tv_nsec: 327527516 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 327527516 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 15, rate_sample: RateSample { bandwidth: 303.39 Kbps, is_app_limited: true, interval: 136.570342ms, delivered: 234, prior_delivered: 1664, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 222837312 }), send_elapsed: 135.704129ms, ack_elapsed: 136.570342ms, rtt: 31.880138ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 251650, last_update: Instant { tv_sec: 714, tv_nsec: 327527516 }, next_time: Instant { tv_sec: 714, tv_nsec: 327527516 }, 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=1.3794620881905602 w_max=8032.5 } [2025-09-12T02:38:47.492024059Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:41243 to 193.167.100.100:443 [2025-09-12T02:38:47.492030801Z TRACE quiche_apps::client] 0.0.0.0:41243 -> 193.167.100.100:443: done writing [2025-09-12T02:38:47.685287033Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:47.685312120Z TRACE quiche] ee4fa5d5eebc3ae8057948f596a4a3f93f285203 draining timeout expired [2025-09-12T02:38:47.685379616Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:47.685386990Z INFO quiche_apps::client] connection closed, recv=8 sent=17 lost=6 retrans=9 sent_bytes=3039 recv_bytes=3013 lost_bytes=865 [local_addr=0.0.0.0:41243 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=8 sent=17 lost=6 retrans=9 rtt=32.858064ms min_rtt=Some(31.013925ms) rttvar=7.860563ms cwnd=6615 sent_bytes=3039 recv_bytes=3013 lost_bytes=0 stream_retrans_bytes=966 pmtu=1350 delivery_rate=37924] [2025-09-12T02:38:47.690270766Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-09-12T02:38:47.690532356Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:45059 with scid c9a5dcf21f488b3203d593af097b1fc9204396e2 [2025-09-12T02:38:47.690576258Z TRACE quiche::tls] c9a5dcf21f488b3203d593af097b1fc9204396e2 write message lvl=Initial len=266 [2025-09-12T02:38:47.690592268Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=5abb2901839f0f0104fc24946cc57b02 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=270 pn=0 [2025-09-12T02:38:47.690595444Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm CRYPTO off=0 len=266 [2025-09-12T02:38:47.690607617Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=998.932974ms 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: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 714, tv_nsec: 558194723 }, next_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, 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-09-12T02:38:47.690680944Z TRACE quiche_apps::client] written 1200 [2025-09-12T02:38:48.690743949Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:48.690777763Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 loss detection timeout expired [2025-09-12T02:38:48.690784986Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=997.754643ms 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: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 714, tv_nsec: 558194723 }, next_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, 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-09-12T02:38:48.690828127Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:48.690871368Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=5abb2901839f0f0104fc24946cc57b02 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=270 pn=1 [2025-09-12T02:38:48.690876117Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm CRYPTO off=0 len=266 [2025-09-12T02:38:48.690896926Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=1.997949315s 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: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 715, tv_nsec: 558500323 }, next_time: Instant { tv_sec: 715, tv_nsec: 558500323 }, 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-09-12T02:38:48.690979601Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:38:48.690987425Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:38:49.722922254Z TRACE quiche_apps::client] got 768 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:38:49.722957540Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 dropped invalid packet [2025-09-12T02:38:49.722968560Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 768 bytes [2025-09-12T02:38:49.722976475Z TRACE quiche_apps::client] got 70 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:38:49.722982737Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 dropped invalid packet [2025-09-12T02:38:49.722986855Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 70 bytes [2025-09-12T02:38:49.722993197Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:38:49.722998807Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:49.723016560Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:38:50.690046147Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:50.690078057Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 loss detection timeout expired [2025-09-12T02:38:50.690085220Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=1.996759919s 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: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 715, tv_nsec: 558500323 }, next_time: Instant { tv_sec: 715, tv_nsec: 558500323 }, 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-09-12T02:38:50.690127279Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:50.690164609Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=5abb2901839f0f0104fc24946cc57b02 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=270 pn=2 [2025-09-12T02:38:50.690169308Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm CRYPTO off=0 len=266 [2025-09-12T02:38:50.690189776Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=3.995947632s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=999 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 717, tv_nsec: 557791530 }, next_time: Instant { tv_sec: 717, tv_nsec: 557791530 }, 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-09-12T02:38:50.690269756Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:38:50.690283732Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=5abb2901839f0f0104fc24946cc57b02 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=4 pn=3 [2025-09-12T02:38:50.690287759Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm PING mtu_probe=None [2025-09-12T02:38:50.690291397Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm PADDING len=3 [2025-09-12T02:38:50.690299802Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=3.995975444s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1066 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 717, tv_nsec: 557929278 }, next_time: Instant { tv_sec: 717, tv_nsec: 557929278 }, 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-09-12T02:38:50.690374432Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:38:50.690386134Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:38:54.690446116Z TRACE quiche_apps::client] timed out [2025-09-12T02:38:54.690486872Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 loss detection timeout expired [2025-09-12T02:38:54.690495709Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=3.991778696s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1066 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 717, tv_nsec: 557929278 }, next_time: Instant { tv_sec: 717, tv_nsec: 557929278 }, 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-09-12T02:38:54.690522920Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:54.690561522Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=5abb2901839f0f0104fc24946cc57b02 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=270 pn=4 [2025-09-12T02:38:54.690567914Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm CRYPTO off=0 len=266 [2025-09-12T02:38:54.690590787Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=7.991944216s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1399 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 721, tv_nsec: 558189145 }, next_time: Instant { tv_sec: 721, tv_nsec: 558189145 }, 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-09-12T02:38:54.690673712Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:38:54.690690003Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=5abb2901839f0f0104fc24946cc57b02 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=4 pn=5 [2025-09-12T02:38:54.690695693Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm PING mtu_probe=None [2025-09-12T02:38:54.690700943Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm PADDING len=3 [2025-09-12T02:38:54.690727764Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=7.991953302s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1466 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 721, tv_nsec: 558335148 }, next_time: Instant { tv_sec: 721, tv_nsec: 558335148 }, 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-09-12T02:38:54.690782396Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:38:54.690791994Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:38:57.720508774Z TRACE quiche_apps::client] got 768 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:38:57.720544551Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 dropped invalid packet [2025-09-12T02:38:57.720551935Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 768 bytes [2025-09-12T02:38:57.720559840Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:38:57.720565009Z TRACE quiche_apps::client] done reading [2025-09-12T02:38:57.720583424Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:02.688609691Z TRACE quiche_apps::client] timed out [2025-09-12T02:39:02.688645929Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 loss detection timeout expired [2025-09-12T02:39:02.688654896Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=7.986025388s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1466 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 721, tv_nsec: 558335148 }, next_time: Instant { tv_sec: 721, tv_nsec: 558335148 }, 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-09-12T02:39:02.688682147Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:02.688719277Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=5abb2901839f0f0104fc24946cc57b02 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=270 pn=6 [2025-09-12T02:39:02.688725639Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm CRYPTO off=0 len=266 [2025-09-12T02:39:02.688747560Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=15.983945608s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1799 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 729, tv_nsec: 556347220 }, next_time: Instant { tv_sec: 729, tv_nsec: 556347220 }, 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-09-12T02:39:02.688841375Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:02.688858067Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=5abb2901839f0f0104fc24946cc57b02 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=4 pn=7 [2025-09-12T02:39:02.688865120Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm PING mtu_probe=None [2025-09-12T02:39:02.688869979Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm PADDING len=3 [2025-09-12T02:39:02.688878916Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=15.983968341s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1866 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 558194723 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 729, tv_nsec: 556501349 }, next_time: Instant { tv_sec: 729, tv_nsec: 556501349 }, 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-09-12T02:39:02.688910244Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:02.688915805Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:08.712812159Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:08.712855550Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Initial version=1 dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 scid=48c479cf2294340a46e268b2907375df985abcf4 token= len=121 pn=13 [2025-09-12T02:39:08.712883232Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm ACK delay=748797 blocks=[0..2, 6..7] ecn_counts=None [2025-09-12T02:39:08.712898350Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 0 [2025-09-12T02:39:08.712908048Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 1 [2025-09-12T02:39:08.712915142Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 2 [2025-09-12T02:39:08.712922375Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 6 [2025-09-12T02:39:08.712930210Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 7 [2025-09-12T02:39:08.712953283Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet 3 lost on epoch 0 [2025-09-12T02:39:08.712959415Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet 4 lost on epoch 0 [2025-09-12T02:39:08.712964805Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet 5 lost on epoch 0 [2025-09-12T02:39:08.712989481Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm CRYPTO off=0 len=90 [2025-09-12T02:39:08.713126137Z TRACE quiche::tls] c9a5dcf21f488b3203d593af097b1fc9204396e2 set write secret lvl=Handshake [2025-09-12T02:39:08.713140735Z TRACE quiche::tls] c9a5dcf21f488b3203d593af097b1fc9204396e2 set read secret lvl=Handshake [2025-09-12T02:39:08.713186510Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 dropped invalid packet [2025-09-12T02:39:08.713190909Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 1200 bytes [2025-09-12T02:39:08.713196750Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:39:08.713200216Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:08.713220134Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=48c479cf2294340a46e268b2907375df985abcf4 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=5 pn=8 [2025-09-12T02:39:08.713224321Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=46 blocks=[13..13] ecn_counts=None [2025-09-12T02:39:08.713235653Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=18.07160984s latest_rtt=6.023999597s srtt=6.023999597s min_rtt=6.023999597s rttvar=3.011999798s cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 735, tv_nsec: 580500946 }) Rate { delivered: 1399, delivered_time: Instant { tv_sec: 729, tv_nsec: 556501349 }, first_sent_time: Instant { tv_sec: 729, tv_nsec: 556501349 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 7, rate_sample: RateSample { bandwidth: 0.53 Kbps, is_app_limited: true, interval: 21.022306223s, delivered: 1399, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 558194723 }), send_elapsed: 14.998306626s, ack_elapsed: 21.022306223s, rtt: 6.023999597s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 1960, last_update: Instant { tv_sec: 729, tv_nsec: 556501349 }, next_time: Instant { tv_sec: 729, tv_nsec: 556501349 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.713298080Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.713305173Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:08.713713739Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:08.713728697Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Initial version=1 dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 scid=48c479cf2294340a46e268b2907375df985abcf4 token= len=28 pn=14 [2025-09-12T02:39:08.713740329Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm ACK delay=748821 blocks=[0..2, 6..7] ecn_counts=None [2025-09-12T02:39:08.713746591Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm PING mtu_probe=None [2025-09-12T02:39:08.713761819Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 dropped invalid packet [2025-09-12T02:39:08.713766478Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 1200 bytes [2025-09-12T02:39:08.713772329Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:39:08.713782498Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:08.713800381Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Initial version=1 dcid=48c479cf2294340a46e268b2907375df985abcf4 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=5 pn=9 [2025-09-12T02:39:08.713805070Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=9 blocks=[13..14] ecn_counts=None [2025-09-12T02:39:08.713815850Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=18.071029442s latest_rtt=6.023999597s srtt=6.023999597s min_rtt=6.023999597s rttvar=3.011999798s cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 735, tv_nsec: 580500946 }) Rate { delivered: 1399, delivered_time: Instant { tv_sec: 729, tv_nsec: 556501349 }, first_sent_time: Instant { tv_sec: 729, tv_nsec: 556501349 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 7, rate_sample: RateSample { bandwidth: 0.53 Kbps, is_app_limited: true, interval: 21.022306223s, delivered: 1399, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 558194723 }), send_elapsed: 14.998306626s, ack_elapsed: 21.022306223s, rtt: 6.023999597s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 1960, last_update: Instant { tv_sec: 729, tv_nsec: 556501349 }, next_time: Instant { tv_sec: 729, tv_nsec: 556501349 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.713862087Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.713871425Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:08.745819423Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:08.745838609Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Initial version=1 dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 scid=48c479cf2294340a46e268b2907375df985abcf4 token= len=22 pn=15 [2025-09-12T02:39:08.745852275Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm ACK delay=11 blocks=[8..8] ecn_counts=None [2025-09-12T02:39:08.745859558Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 8 [2025-09-12T02:39:08.745881550Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 dropped invalid packet [2025-09-12T02:39:08.745885647Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 1200 bytes [2025-09-12T02:39:08.745893933Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:39:08.745898872Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:08.745910033Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:08.746450797Z TRACE quiche_apps::client] got 768 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:08.746467067Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Handshake version=1 dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 scid=48c479cf2294340a46e268b2907375df985abcf4 len=719 pn=16 [2025-09-12T02:39:08.746479952Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm CRYPTO off=0 len=698 [2025-09-12T02:39:08.746754887Z TRACE quiche::tls] c9a5dcf21f488b3203d593af097b1fc9204396e2 write message lvl=Handshake len=36 [2025-09-12T02:39:08.746759626Z TRACE quiche::tls] c9a5dcf21f488b3203d593af097b1fc9204396e2 set write secret lvl=OneRTT [2025-09-12T02:39:08.746767130Z TRACE quiche::tls] c9a5dcf21f488b3203d593af097b1fc9204396e2 set read secret lvl=OneRTT [2025-09-12T02:39:08.746783060Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 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(5abb2901839f0f0104fc24946cc57b02), 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(48c479cf2294340a46e268b2907375df985abcf4), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-09-12T02:39:08.746812415Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 768 bytes [2025-09-12T02:39:08.746817124Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:39:08.746819548Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:08.746855516Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm SETTINGS stream=2 len=18 [2025-09-12T02:39:08.746869201Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 open GREASE stream 14 [2025-09-12T02:39:08.746877537Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm GREASE stream=0 len=0 [2025-09-12T02:39:08.746883308Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm GREASE stream=0 len=18 [2025-09-12T02:39:08.746894749Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm HEADERS stream=0 len=30 fin=true [2025-09-12T02:39:08.746900870Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /rzjkzhphna", "user-agent: quiche"] [2025-09-12T02:39:08.746981261Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Handshake version=1 dcid=48c479cf2294340a46e268b2907375df985abcf4 scid=c9a5dcf21f488b3203d593af097b1fc9204396e2 len=46 pn=10 [2025-09-12T02:39:08.746985329Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=64 blocks=[16..16] ecn_counts=None [2025-09-12T02:39:08.746988705Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm CRYPTO off=0 len=36 [2025-09-12T02:39:08.746998524Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=18.071971918s latest_rtt=6.023999597s srtt=6.023999597s min_rtt=6.023999597s rttvar=3.011999798s cwnd=9450 ssthresh=9450 bytes_in_flight=112 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1399, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { bandwidth: 0.53 Kbps, is_app_limited: true, interval: 6.056988006s, delivered: 0, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 729, tv_nsec: 556501349 }), send_elapsed: 0ns, ack_elapsed: 6.056988006s, rtt: 6.056988006s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 1960, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.747017108Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 dropped epoch 0 state [2025-09-12T02:39:08.747026817Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=304 pn=11 [2025-09-12T02:39:08.747029692Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[18, 2e, 3f, 8e, cc, 7f, 52, 3f, 21, ed, f6, 16, c5, 02, fc, 16, 2c, 40, ee, 8a] reset_token=[7f, 7f, 29, d7, 7e, e4, ac, 50, 96, da, c3, 2d, c5, cb, 66, 8a] [2025-09-12T02:39:08.747041745Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[8d, 3f, 9c, e7, 22, 22, e2, b5, 1b, 42, 7b, c1, ee, 2b, 37, bd, f0, b4, bf, 7e] reset_token=[83, ab, e4, d5, 2b, 78, 05, bb, b1, be, d4, ae, 13, 78, 59, 2d] [2025-09-12T02:39:08.747048157Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[be, d0, 1b, cb, 53, ea, 00, 30, 5e, ff, d2, 36, 9c, 15, e1, 1c, 00, 96, e1, c0] reset_token=[9e, 45, 58, f5, 6f, bd, 7b, 5a, 9e, 91, 95, b2, b5, 3b, 69, 14] [2025-09-12T02:39:08.747054348Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[17, 90, ce, d3, 22, 2d, ba, 59, d5, b7, 37, 71, 0c, b7, 29, 1d, 30, 6a, d5, 01] reset_token=[23, 41, ee, 50, 8e, c2, fd, ed, 4a, f4, e6, db, 7e, d1, 71, 7f] [2025-09-12T02:39:08.747060320Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[ab, 99, 70, 3b, dc, 60, 2d, 19, 6d, 28, f8, 3c, e1, f0, 78, f2, a5, 34, 88, 83] reset_token=[c0, ff, a1, c0, ea, 80, ef, 15, 27, 93, a7, a4, 84, c1, ac, 07] [2025-09-12T02:39:08.747066180Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[3e, 08, 19, 7e, 71, fc, 07, e6, ee, bd, 41, f5, ca, 8a, 5e, 64, d2, 7c, e3, bc] reset_token=[7a, 2a, ba, 6d, 78, cb, b8, a1, 97, fe, 3d, ab, f7, 1c, 91, f1] [2025-09-12T02:39:08.747072272Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[38, c9, b3, e3, 43, 00, fb, f8, ab, 48, 72, b8, 52, 09, 4f, 09, 04, ee, 02, cd] reset_token=[91, c8, 90, fc, 0c, cd, 85, 80, ee, 5a, f4, 7a, 36, a3, a3, 64] [2025-09-12T02:39:08.747078423Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm STREAM id=2 off=0 len=19 fin=false [2025-09-12T02:39:08.747087851Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=18.071882741s latest_rtt=6.023999597s srtt=6.023999597s min_rtt=6.023999597s rttvar=3.011999798s cwnd=9450 ssthresh=9450 bytes_in_flight=454 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1399, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 8, rate_sample: RateSample { bandwidth: 0.53 Kbps, is_app_limited: true, interval: 6.056988006s, delivered: 0, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 729, tv_nsec: 556501349 }), send_elapsed: 0ns, ack_elapsed: 6.056988006s, rtt: 6.056988006s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 1960, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.747124750Z TRACE quiche_apps::client] written 454 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.747132785Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=6 pn=12 [2025-09-12T02:39:08.747135691Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm STREAM id=6 off=0 len=1 fin=false [2025-09-12T02:39:08.747140860Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=18.071829822s latest_rtt=6.023999597s srtt=6.023999597s min_rtt=6.023999597s rttvar=3.011999798s cwnd=9450 ssthresh=9450 bytes_in_flight=498 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1399, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 8, rate_sample: RateSample { bandwidth: 0.53 Kbps, is_app_limited: true, interval: 6.056988006s, delivered: 0, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 729, tv_nsec: 556501349 }), send_elapsed: 0ns, ack_elapsed: 6.056988006s, rtt: 6.056988006s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 1960, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.747172189Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.747179062Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=6 pn=13 [2025-09-12T02:39:08.747181767Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm STREAM id=10 off=0 len=1 fin=false [2025-09-12T02:39:08.747186656Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=18.071784006s latest_rtt=6.023999597s srtt=6.023999597s min_rtt=6.023999597s rttvar=3.011999798s cwnd=9450 ssthresh=9450 bytes_in_flight=542 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1399, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 8, rate_sample: RateSample { bandwidth: 0.53 Kbps, is_app_limited: true, interval: 6.056988006s, delivered: 0, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 729, tv_nsec: 556501349 }), send_elapsed: 0ns, ack_elapsed: 6.056988006s, rtt: 6.056988006s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 1960, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.747215600Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.747226601Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=73 pn=14 [2025-09-12T02:39:08.747230809Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:39:08.747239756Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=18.071730486s latest_rtt=6.023999597s srtt=6.023999597s min_rtt=6.023999597s rttvar=3.011999798s cwnd=9450 ssthresh=9450 bytes_in_flight=653 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1399, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 8, rate_sample: RateSample { bandwidth: 0.53 Kbps, is_app_limited: true, interval: 6.056988006s, delivered: 0, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 729, tv_nsec: 556501349 }), send_elapsed: 0ns, ack_elapsed: 6.056988006s, rtt: 6.056988006s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 1960, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.747284129Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.747296462Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=31 pn=15 [2025-09-12T02:39:08.747301101Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm STREAM id=14 off=0 len=26 fin=true [2025-09-12T02:39:08.747310679Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=18.071659553s latest_rtt=6.023999597s srtt=6.023999597s min_rtt=6.023999597s rttvar=3.011999798s cwnd=9450 ssthresh=9450 bytes_in_flight=722 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1399, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 8, rate_sample: RateSample { bandwidth: 0.53 Kbps, is_app_limited: true, interval: 6.056988006s, delivered: 0, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 729, tv_nsec: 556501349 }), send_elapsed: 0ns, ack_elapsed: 6.056988006s, rtt: 6.056988006s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 1960, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.747343901Z TRACE quiche_apps::client] written 69 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.747373476Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:08.779125282Z TRACE quiche_apps::client] got 750 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:08.779147553Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Short dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 key_phase=false len=729 pn=17 [2025-09-12T02:39:08.779161730Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm ACK delay=3 blocks=[11..12, 15..15] ecn_counts=None [2025-09-12T02:39:08.779169655Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 11 [2025-09-12T02:39:08.779175356Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 12 [2025-09-12T02:39:08.779182118Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 15 [2025-09-12T02:39:08.779194011Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[48, 08, df, 35, a2, 80, 43, 2c, 36, 96, e1, 15, 8c, 9d, 46, 51, 17, 7b, da, 6a] reset_token=[bc, ca, 1c, f2, 31, ef, e1, 2d, 67, 1e, c9, 70, a5, f6, 01, 74] [2025-09-12T02:39:08.779209600Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[e2, 2a, ad, 50, 58, ce, 9d, 60, 73, e0, 90, 74, ec, 87, fa, db, 31, 1b, a8, 8e] reset_token=[7b, b8, d8, 7f, 44, ee, 4b, e5, c3, 70, a5, f2, 08, 55, 68, 51] [2025-09-12T02:39:08.779232663Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[37, fb, bc, 0a, 2b, 1c, 24, 97, d6, 3f, 0f, d9, c9, c3, 7d, db, 64, 85, 4d, 74] reset_token=[2b, a6, b4, e4, 52, bd, 25, e9, 4a, 1d, 8c, e3, 6f, c4, e7, 96] [2025-09-12T02:39:08.779245697Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[d9, 9b, f1, e5, ab, 3a, c4, 62, f0, b9, c6, 58, d1, 0d, d6, 63, 05, c8, 7b, 00] reset_token=[f3, 5d, 47, 2a, 82, d8, 62, 34, 49, bc, 94, 46, 72, 56, 8d, 6d] [2025-09-12T02:39:08.779258461Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[f5, 8f, 2e, 70, 46, 16, b4, bc, c5, 03, be, 81, b5, 27, 43, 07, 29, 4c, 18, 92] reset_token=[95, ed, f2, 2a, 87, 4c, 37, 53, e9, e9, dc, d6, 18, 81, 94, ea] [2025-09-12T02:39:08.779271085Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[99, 00, 4e, db, 39, bc, 83, 42, 63, 94, 4f, 84, 9a, f6, b7, d8, a2, a6, 12, c7] reset_token=[e8, d7, f1, 3a, 99, 7a, bd, 4c, 56, ef, b9, a8, 2a, 21, 18, 50] [2025-09-12T02:39:08.779283629Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[59, 17, d0, 5f, 62, 97, 57, 1b, 02, c3, ff, 2e, 48, 7d, 6f, e1, a2, bc, 27, 42] reset_token=[6f, ad, 96, f7, 8b, 16, 7c, f3, cc, f0, d7, 77, de, 6f, 88, 55] [2025-09-12T02:39:08.779294028Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm HANDSHAKE_DONE [2025-09-12T02:39:08.779300430Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 dropped epoch 1 state [2025-09-12T02:39:08.779306261Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm CRYPTO off=0 len=396 [2025-09-12T02:39:08.779340956Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm STREAM id=3 off=0 len=19 fin=false [2025-09-12T02:39:08.779424302Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 750 bytes [2025-09-12T02:39:08.779435834Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:08.779443679Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Short dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 key_phase=false len=23 pn=18 [2025-09-12T02:39:08.779454329Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm STREAM id=7 off=0 len=1 fin=false [2025-09-12T02:39:08.779466321Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 44 bytes [2025-09-12T02:39:08.779475178Z TRACE quiche_apps::client] got 69 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:08.779481379Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Short dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 key_phase=false len=48 pn=20 [2025-09-12T02:39:08.779489775Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm STREAM id=15 off=0 len=26 fin=true [2025-09-12T02:39:08.779500145Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 69 bytes [2025-09-12T02:39:08.779506757Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:39:08.779511586Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:08.779516355Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 stream id 3 is readable [2025-09-12T02:39:08.779525672Z TRACE quiche::h3::stream] c9a5dcf21f488b3203d593af097b1fc9204396e2 read 1 bytes on stream 3 [2025-09-12T02:39:08.779535020Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 open peer's control stream 3 [2025-09-12T02:39:08.779541723Z TRACE quiche::h3::stream] c9a5dcf21f488b3203d593af097b1fc9204396e2 read 1 bytes on stream 3 [2025-09-12T02:39:08.779545980Z TRACE quiche::h3::stream] c9a5dcf21f488b3203d593af097b1fc9204396e2 read 1 bytes on stream 3 [2025-09-12T02:39:08.779559726Z TRACE quiche::h3::stream] c9a5dcf21f488b3203d593af097b1fc9204396e2 read 16 bytes on stream 3 [2025-09-12T02:39:08.779566699Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(4014201769366384714, 2554981101687414304)]), additional_settings=Some([(4014201769366384714, 2554981101687414304)]) stream=3 payload_len=16 [2025-09-12T02:39:08.779580355Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 stream id 7 is readable [2025-09-12T02:39:08.779588290Z TRACE quiche::h3::stream] c9a5dcf21f488b3203d593af097b1fc9204396e2 read 1 bytes on stream 7 [2025-09-12T02:39:08.779595383Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 stream id 15 is readable [2025-09-12T02:39:08.779600242Z TRACE quiche::h3::stream] c9a5dcf21f488b3203d593af097b1fc9204396e2 read 1 bytes on stream 15 [2025-09-12T02:39:08.779604350Z TRACE quiche::h3::stream] c9a5dcf21f488b3203d593af097b1fc9204396e2 read 7 bytes on stream 15 [2025-09-12T02:39:08.779618887Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=7 pn=16 [2025-09-12T02:39:08.779622835Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=17 blocks=[17..18, 20..20] ecn_counts=None [2025-09-12T02:39:08.779633054Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=5.901737378s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=155 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 646798781 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 15, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 32.173056ms, delivered: 455, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 614625725 }), send_elapsed: 0ns, ack_elapsed: 32.173056ms, rtt: 32.173056ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.779684660Z TRACE quiche_apps::client] written 45 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.779691083Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:08.912826712Z TRACE quiche_apps::client] got 753 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:08.912849675Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Short dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 key_phase=false len=732 pn=21 [2025-09-12T02:39:08.912865124Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm ACK delay=16708 blocks=[11..12, 15..15] ecn_counts=None [2025-09-12T02:39:08.912879240Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[48, 08, df, 35, a2, 80, 43, 2c, 36, 96, e1, 15, 8c, 9d, 46, 51, 17, 7b, da, 6a] reset_token=[bc, ca, 1c, f2, 31, ef, e1, 2d, 67, 1e, c9, 70, a5, f6, 01, 74] [2025-09-12T02:39:08.912888818Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[e2, 2a, ad, 50, 58, ce, 9d, 60, 73, e0, 90, 74, ec, 87, fa, db, 31, 1b, a8, 8e] reset_token=[7b, b8, d8, 7f, 44, ee, 4b, e5, c3, 70, a5, f2, 08, 55, 68, 51] [2025-09-12T02:39:08.912897965Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[37, fb, bc, 0a, 2b, 1c, 24, 97, d6, 3f, 0f, d9, c9, c3, 7d, db, 64, 85, 4d, 74] reset_token=[2b, a6, b4, e4, 52, bd, 25, e9, 4a, 1d, 8c, e3, 6f, c4, e7, 96] [2025-09-12T02:39:08.912916680Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[d9, 9b, f1, e5, ab, 3a, c4, 62, f0, b9, c6, 58, d1, 0d, d6, 63, 05, c8, 7b, 00] reset_token=[f3, 5d, 47, 2a, 82, d8, 62, 34, 49, bc, 94, 46, 72, 56, 8d, 6d] [2025-09-12T02:39:08.912929564Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[f5, 8f, 2e, 70, 46, 16, b4, bc, c5, 03, be, 81, b5, 27, 43, 07, 29, 4c, 18, 92] reset_token=[95, ed, f2, 2a, 87, 4c, 37, 53, e9, e9, dc, d6, 18, 81, 94, ea] [2025-09-12T02:39:08.912943030Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[99, 00, 4e, db, 39, bc, 83, 42, 63, 94, 4f, 84, 9a, f6, b7, d8, a2, a6, 12, c7] reset_token=[e8, d7, f1, 3a, 99, 7a, bd, 4c, 56, ef, b9, a8, 2a, 21, 18, 50] [2025-09-12T02:39:08.912957998Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[59, 17, d0, 5f, 62, 97, 57, 1b, 02, c3, ff, 2e, 48, 7d, 6f, e1, a2, bc, 27, 42] reset_token=[6f, ad, 96, f7, 8b, 16, 7c, f3, cc, f0, d7, 77, de, 6f, 88, 55] [2025-09-12T02:39:08.912970722Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm HANDSHAKE_DONE [2025-09-12T02:39:08.912976382Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm CRYPTO off=0 len=396 [2025-09-12T02:39:08.912983926Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm STREAM id=3 off=0 len=19 fin=false [2025-09-12T02:39:08.913009895Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 753 bytes [2025-09-12T02:39:08.913018461Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:39:08.913023851Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:08.913045432Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=7 pn=17 [2025-09-12T02:39:08.913051724Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=24 blocks=[17..18, 20..21] ecn_counts=None [2025-09-12T02:39:08.913066191Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=5.76830357s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=155 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 646798781 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 15, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 32.173056ms, delivered: 455, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 614625725 }), send_elapsed: 0ns, ack_elapsed: 32.173056ms, rtt: 32.173056ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:08.913124229Z TRACE quiche_apps::client] written 45 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:08.913130982Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:09.178570726Z TRACE quiche_apps::client] got 753 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:09.178614117Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Short dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 key_phase=false len=732 pn=22 [2025-09-12T02:39:09.178638934Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm ACK delay=49915 blocks=[11..12, 15..15] ecn_counts=None [2025-09-12T02:39:09.178657489Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[48, 08, df, 35, a2, 80, 43, 2c, 36, 96, e1, 15, 8c, 9d, 46, 51, 17, 7b, da, 6a] reset_token=[bc, ca, 1c, f2, 31, ef, e1, 2d, 67, 1e, c9, 70, a5, f6, 01, 74] [2025-09-12T02:39:09.178672817Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[e2, 2a, ad, 50, 58, ce, 9d, 60, 73, e0, 90, 74, ec, 87, fa, db, 31, 1b, a8, 8e] reset_token=[7b, b8, d8, 7f, 44, ee, 4b, e5, c3, 70, a5, f2, 08, 55, 68, 51] [2025-09-12T02:39:09.178685661Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[37, fb, bc, 0a, 2b, 1c, 24, 97, d6, 3f, 0f, d9, c9, c3, 7d, db, 64, 85, 4d, 74] reset_token=[2b, a6, b4, e4, 52, bd, 25, e9, 4a, 1d, 8c, e3, 6f, c4, e7, 96] [2025-09-12T02:39:09.178699818Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[d9, 9b, f1, e5, ab, 3a, c4, 62, f0, b9, c6, 58, d1, 0d, d6, 63, 05, c8, 7b, 00] reset_token=[f3, 5d, 47, 2a, 82, d8, 62, 34, 49, bc, 94, 46, 72, 56, 8d, 6d] [2025-09-12T02:39:09.178708855Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[f5, 8f, 2e, 70, 46, 16, b4, bc, c5, 03, be, 81, b5, 27, 43, 07, 29, 4c, 18, 92] reset_token=[95, ed, f2, 2a, 87, 4c, 37, 53, e9, e9, dc, d6, 18, 81, 94, ea] [2025-09-12T02:39:09.178720587Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[99, 00, 4e, db, 39, bc, 83, 42, 63, 94, 4f, 84, 9a, f6, b7, d8, a2, a6, 12, c7] reset_token=[e8, d7, f1, 3a, 99, 7a, bd, 4c, 56, ef, b9, a8, 2a, 21, 18, 50] [2025-09-12T02:39:09.178732339Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[59, 17, d0, 5f, 62, 97, 57, 1b, 02, c3, ff, 2e, 48, 7d, 6f, e1, a2, bc, 27, 42] reset_token=[6f, ad, 96, f7, 8b, 16, 7c, f3, cc, f0, d7, 77, de, 6f, 88, 55] [2025-09-12T02:39:09.178742638Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm HANDSHAKE_DONE [2025-09-12T02:39:09.178748058Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm CRYPTO off=0 len=396 [2025-09-12T02:39:09.178755693Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm STREAM id=3 off=0 len=19 fin=false [2025-09-12T02:39:09.178788434Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 753 bytes [2025-09-12T02:39:09.178797511Z TRACE quiche_apps::client] got 54 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:09.178804204Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Short dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 key_phase=false len=33 pn=23 [2025-09-12T02:39:09.178813541Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm ACK delay=49947 blocks=[11..12, 15..15] ecn_counts=None [2025-09-12T02:39:09.178819683Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm STREAM id=7 off=0 len=1 fin=false [2025-09-12T02:39:09.178828499Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 54 bytes [2025-09-12T02:39:09.178834711Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:39:09.178839430Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:09.178862483Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=7 pn=18 [2025-09-12T02:39:09.178868534Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=6 blocks=[17..18, 20..23] ecn_counts=None [2025-09-12T02:39:09.178890846Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=5.502479345s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=155 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 741, tv_nsec: 638625322 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 646798781 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 15, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 32.173056ms, delivered: 455, prior_delivered: 1399, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 614625725 }), send_elapsed: 0ns, ack_elapsed: 32.173056ms, rtt: 32.173056ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:09.178951350Z TRACE quiche_apps::client] written 45 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:09.178960447Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:09.330138950Z TRACE quiche_apps::client] got 50 bytes from 193.167.100.100:443 to 0.0.0.0:45059 [2025-09-12T02:39:09.330171430Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx pkt Short dcid=c9a5dcf21f488b3203d593af097b1fc9204396e2 key_phase=false len=29 pn=25 [2025-09-12T02:39:09.330191217Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm ACK delay=15063 blocks=[18..18] ecn_counts=None [2025-09-12T02:39:09.330202599Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet newly acked 18 [2025-09-12T02:39:09.330209923Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet 13 lost on epoch 2 [2025-09-12T02:39:09.330215383Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 packet 14 lost on epoch 2 [2025-09-12T02:39:09.330223979Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 rx frm STREAM id=11 off=0 len=1 fin=false [2025-09-12T02:39:09.330279613Z TRACE quiche_apps::client] 0.0.0.0:45059: processed 50 bytes [2025-09-12T02:39:09.330290153Z TRACE quiche_apps::client] 0.0.0.0:45059: recv() would block [2025-09-12T02:39:09.330296054Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:09.330304019Z TRACE quiche::h3] c9a5dcf21f488b3203d593af097b1fc9204396e2 stream id 11 is readable [2025-09-12T02:39:09.330316242Z TRACE quiche::h3::stream] c9a5dcf21f488b3203d593af097b1fc9204396e2 read 1 bytes on stream 11 [2025-09-12T02:39:09.330371506Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=11 pn=19 [2025-09-12T02:39:09.330379771Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=21 blocks=[25..25] ecn_counts=None [2025-09-12T02:39:09.330385602Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm STREAM id=10 off=0 len=1 fin=false [2025-09-12T02:39:09.330400009Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=5.350970473s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 742, tv_nsec: 221665380 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 18, last_sent_packet: 19, largest_acked: 18, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 551.02049ms, delivered: 0, prior_delivered: 1854, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 646798781 }), send_elapsed: 0ns, ack_elapsed: 551.02049ms, rtt: 583.193546ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:09.330463197Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:09.330479939Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=73 pn=20 [2025-09-12T02:39:09.330485709Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm STREAM id=0 off=0 len=68 fin=true [2025-09-12T02:39:09.330498113Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=5.350871988s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=160 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 742, tv_nsec: 221665380 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 19, last_sent_packet: 20, largest_acked: 18, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 551.02049ms, delivered: 0, prior_delivered: 1854, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 646798781 }), send_elapsed: 0ns, ack_elapsed: 551.02049ms, rtt: 583.193546ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:09.330542957Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:09.330551573Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:14.686970840Z TRACE quiche_apps::client] timed out [2025-09-12T02:39:14.687007789Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 loss detection timeout expired [2025-09-12T02:39:14.687017358Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=14.971299934s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=160 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 742, tv_nsec: 221665380 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 19, last_sent_packet: 20, largest_acked: 18, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 551.02049ms, delivered: 0, prior_delivered: 1854, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 646798781 }), send_elapsed: 0ns, ack_elapsed: 551.02049ms, rtt: 583.193546ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:14.687064616Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:14.687093410Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=8 pn=21 [2025-09-12T02:39:14.687098309Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=669615 blocks=[25..25] ecn_counts=None [2025-09-12T02:39:14.687118236Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=14.971199866s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=160 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 742, tv_nsec: 221665380 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 19, last_sent_packet: 21, largest_acked: 18, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 551.02049ms, delivered: 0, prior_delivered: 1854, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 646798781 }), send_elapsed: 0ns, ack_elapsed: 551.02049ms, rtt: 583.193546ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:14.687204418Z TRACE quiche_apps::client] written 46 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:14.687215078Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:39:29.674248443Z TRACE quiche_apps::client] timed out [2025-09-12T02:39:29.674287416Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 loss detection timeout expired [2025-09-12T02:39:29.674296784Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=20.311867488s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=160 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 742, tv_nsec: 221665380 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 19, last_sent_packet: 21, largest_acked: 18, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 551.02049ms, delivered: 0, prior_delivered: 1854, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 646798781 }), send_elapsed: 0ns, ack_elapsed: 551.02049ms, rtt: 583.193546ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 735, tv_nsec: 614625725 }, next_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:29.674334564Z TRACE quiche_apps::client] done reading [2025-09-12T02:39:29.674399466Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx pkt Short dcid=48c479cf2294340a46e268b2907375df985abcf4 key_phase=false len=14 pn=22 [2025-09-12T02:39:29.674426437Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm ACK delay=2543028 blocks=[25..25] ecn_counts=None [2025-09-12T02:39:29.674433139Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 tx frm STREAM id=10 off=0 len=1 fin=false [2025-09-12T02:39:29.674452135Z TRACE quiche::recovery::congestion::recovery] c9a5dcf21f488b3203d593af097b1fc9204396e2 timer=40.655617237s latest_rtt=32.173056ms srtt=5.275021279s min_rtt=32.173056ms rttvar=3.756956483s cwnd=9450 ssthresh=9450 bytes_in_flight=212 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 742, tv_nsec: 221665380 }) Rate { delivered: 1854, delivered_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, first_sent_time: Instant { tv_sec: 735, tv_nsec: 614625725 }, end_of_app_limited: 21, last_sent_packet: 22, largest_acked: 18, rate_sample: RateSample { bandwidth: 113.14 Kbps, is_app_limited: true, interval: 551.02049ms, delivered: 0, prior_delivered: 1854, prior_time: Some(Instant { tv_sec: 735, tv_nsec: 646798781 }), send_elapsed: 0ns, ack_elapsed: 551.02049ms, rtt: 583.193546ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 2239, last_update: Instant { tv_sec: 756, tv_nsec: 542029022 }, next_time: Instant { tv_sec: 756, tv_nsec: 542029022 }, 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=1.9574338205844317 w_max=13500 } [2025-09-12T02:39:29.674538286Z TRACE quiche_apps::client] written 52 bytes from 0.0.0.0:45059 to 193.167.100.100:443 [2025-09-12T02:39:29.674545299Z TRACE quiche_apps::client] 0.0.0.0:45059 -> 193.167.100.100:443: done writing [2025-09-12T02:40:10.244411078Z TRACE quiche_apps::client] timed out [2025-09-12T02:40:10.244448698Z TRACE quiche] c9a5dcf21f488b3203d593af097b1fc9204396e2 idle timeout expired [2025-09-12T02:40:10.244506356Z TRACE quiche_apps::client] done reading [2025-09-12T02:40:10.244515834Z INFO quiche_apps::client] connection closed, recv=11 sent=23 lost=7 retrans=11 sent_bytes=3125 recv_bytes=3562 lost_bytes=622 [local_addr=0.0.0.0:45059 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=11 sent=23 lost=7 retrans=11 rtt=5.275021279s min_rtt=Some(32.173056ms) rttvar=3.756956483s cwnd=9450 sent_bytes=3125 recv_bytes=3562 lost_bytes=0 stream_retrans_bytes=2198 pmtu=1350 delivery_rate=14142] [2025-09-12T02:40:10.244532746Z ERROR quiche_apps::common] connection timed out after 82.553848285s and only completed 0/1 requests