[2025-09-12T06:28:44.821302178Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T06:28:44.821615303Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:45062 with scid 9a21dcf5832f7325d1603be97c382a2ce2927487
[2025-09-12T06:28:44.821656440Z TRACE quiche::tls] 9a21dcf5832f7325d1603be97c382a2ce2927487 write message lvl=Initial len=266
[2025-09-12T06:28:44.821673502Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Initial version=1 dcid=1fc25363474c24df42c83b11664282c1 scid=9a21dcf5832f7325d1603be97c382a2ce2927487 len=270 pn=0 
[2025-09-12T06:28:44.821676507Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm CRYPTO off=0 len=266
[2025-09-12T06:28:44.821691175Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=998.931071ms 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: 643, tv_nsec: 611351286 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 611351286 }, 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: 643, tv_nsec: 611351286 }, next_time: Instant { tv_sec: 643, tv_nsec: 611351286 }, 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-12T06:28:44.821744495Z TRACE quiche_apps::client] written 1200
[2025-09-12T06:28:44.860849380Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:45062
[2025-09-12T06:28:44.860863417Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx pkt Initial version=1 dcid=9a21dcf5832f7325d1603be97c382a2ce2927487 scid=50d4f14a35c2210c token= len=117 pn=0 
[2025-09-12T06:28:44.860874167Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm ACK delay=189 blocks=[0..0] ecn_counts=None
[2025-09-12T06:28:44.860879296Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 packet newly acked 0
[2025-09-12T06:28:44.860884266Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm CRYPTO off=0 len=90
[2025-09-12T06:28:44.860968262Z TRACE quiche::tls] 9a21dcf5832f7325d1603be97c382a2ce2927487 set write secret lvl=Handshake
[2025-09-12T06:28:44.860977269Z TRACE quiche::tls] 9a21dcf5832f7325d1603be97c382a2ce2927487 set read secret lvl=Handshake
[2025-09-12T06:28:44.861002076Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx pkt Handshake version=1 dcid=9a21dcf5832f7325d1603be97c382a2ce2927487 scid=50d4f14a35c2210c len=1060 pn=1 
[2025-09-12T06:28:44.861007736Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm CRYPTO off=0 len=710
[2025-09-12T06:28:44.861195727Z TRACE quiche::tls] 9a21dcf5832f7325d1603be97c382a2ce2927487 write message lvl=Handshake len=36
[2025-09-12T06:28:44.861199675Z TRACE quiche::tls] 9a21dcf5832f7325d1603be97c382a2ce2927487 set write secret lvl=OneRTT
[2025-09-12T06:28:44.861206367Z TRACE quiche::tls] 9a21dcf5832f7325d1603be97c382a2ce2927487 set read secret lvl=OneRTT
[2025-09-12T06:28:44.861221185Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 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(1fc25363474c24df42c83b11664282c1), max_idle_timeout: 30000, stateless_reset_token: Some(73715227124744960290605421891654127242), max_udp_payload_size: 65527, initial_max_data: 1572864, initial_max_stream_data_bidi_local: 0, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 12288, initial_max_streams_bidi: 100, initial_max_streams_uni: 3, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(50d4f14a35c2210c), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T06:28:44.861240291Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm PADDING len=329
[2025-09-12T06:28:44.861254628Z TRACE quiche_apps::client] 0.0.0.0:45062: processed 1252 bytes
[2025-09-12T06:28:44.861259357Z TRACE quiche_apps::client] 0.0.0.0:45062: recv() would block
[2025-09-12T06:28:44.861261971Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:44.861304791Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm SETTINGS stream=2 len=18
[2025-09-12T06:28:44.861324518Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm GREASE stream=0 len=0
[2025-09-12T06:28:44.861330790Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm GREASE stream=0 len=18
[2025-09-12T06:28:44.861338284Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm HEADERS stream=0 len=30 fin=true
[2025-09-12T06:28:44.861344505Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /ljwrzzaxry", "user-agent: quiche"]
[2025-09-12T06:28:44.861386694Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm HEADERS stream=4 len=30 fin=true
[2025-09-12T06:28:44.861392305Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /wnlblpwpjq", "user-agent: quiche"]
[2025-09-12T06:28:44.861418203Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm HEADERS stream=8 len=30 fin=true
[2025-09-12T06:28:44.861423022Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /uqbosjbtax", "user-agent: quiche"]
[2025-09-12T06:28:44.861464570Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Initial version=1 dcid=50d4f14a35c2210c scid=9a21dcf5832f7325d1603be97c382a2ce2927487 len=6 pn=1 
[2025-09-12T06:28:44.861468127Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm ACK delay=75 blocks=[0..0] ecn_counts=None
[2025-09-12T06:28:44.861477073Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=117.0972ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 650948291 }, next_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, 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-12T06:28:44.861496830Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Handshake version=1 dcid=50d4f14a35c2210c scid=9a21dcf5832f7325d1603be97c382a2ce2927487 len=45 pn=2 
[2025-09-12T06:28:44.861499706Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm ACK delay=61 blocks=[1..1] ecn_counts=None
[2025-09-12T06:28:44.861507099Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm CRYPTO off=0 len=36
[2025-09-12T06:28:44.861513722Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=117.656204ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 651184793 }, next_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, max_datagram_size: 1350, last_packet_size: None, iv: 230.196µs, 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-12T06:28:44.861525804Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 dropped epoch 0 state
[2025-09-12T06:28:44.861538728Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Short dcid=50d4f14a35c2210c key_phase=false len=1164 pn=3 
[2025-09-12T06:28:44.861541313Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[5b, 62, 49, 8f, 56, 35, 1b, d5, a6, b4, 8b, f9, c3, 9d, 4d, 5d, 26, af, 62, 83] reset_token=[45, fa, 06, 83, 9a, 31, 89, 1c, 05, 70, ec, 62, c3, c5, 22, 87]
[2025-09-12T06:28:44.861548577Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[0b, 49, 45, 32, ac, bc, 5a, d1, 79, 2d, 75, de, 92, 86, 84, 6a, c8, 81, 87, 45] reset_token=[19, 7c, 14, 69, 39, 58, 9e, 13, 97, 1e, 8f, b5, 83, 47, 18, 62]
[2025-09-12T06:28:44.861554408Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[e2, ea, 35, 29, cd, 6a, 00, ce, 70, bd, 96, 7f, a9, b0, 5b, 88, 90, 12, 32, ff] reset_token=[98, d8, ef, bc, c1, f3, b9, 8d, 6f, aa, 4e, 7f, 40, 39, 9f, b2]
[2025-09-12T06:28:44.861560018Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[cf, bc, dd, 13, dc, 3b, 93, 8b, 59, 2e, f5, 16, 5f, 91, 49, 67, 74, 7e, 0e, d2] reset_token=[07, df, 8a, 7a, 90, aa, 0f, c6, ae, e0, f8, 0d, 73, f3, 1a, 64]
[2025-09-12T06:28:44.861565689Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[fd, 46, cc, aa, e0, 63, 91, 38, 6c, f8, 82, 5d, 1d, 8e, 82, b8, 4b, ad, d4, 50] reset_token=[a5, 4f, d9, 68, d7, c6, 57, d6, 42, cf, 6e, 77, 9a, 49, 61, f8]
[2025-09-12T06:28:44.861571149Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[8b, d2, a9, 1e, 17, 1e, fb, f6, 5a, 08, 22, 40, 0b, 47, 0b, b2, 85, 94, 3b, 04] reset_token=[3e, ab, cc, 8a, 80, 29, 3f, b6, 80, ea, 2e, 75, 7b, 32, 4c, 43]
[2025-09-12T06:28:44.861576830Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[4c, 31, 50, c1, 69, 16, 09, 86, 72, 18, 64, 8a, c4, 3f, 26, 29, fc, f8, 09, 25] reset_token=[d9, 94, 05, 12, 63, 4b, 2c, 69, a8, 8a, da, 2f, 99, bf, 06, 53]
[2025-09-12T06:28:44.861582490Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-09-12T06:28:44.861585015Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm PADDING len=860
[2025-09-12T06:28:44.861611284Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=117.558602ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1190, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 651184793 }, next_time: Instant { tv_sec: 643, tv_nsec: 651414989 }, max_datagram_size: 1350, last_packet_size: Some(1190), 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-12T06:28:44.861647632Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:45062 to 193.167.100.100:443
[2025-09-12T06:28:44.861656559Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Short dcid=50d4f14a35c2210c key_phase=false len=6 pn=4 
[2025-09-12T06:28:44.861661107Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-09-12T06:28:44.861670234Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=117.499291ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 651380058 }, next_time: Instant { tv_sec: 643, tv_nsec: 651414989 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.841411ms, 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-12T06:28:44.861713906Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:45062 to 193.167.100.100:443
[2025-09-12T06:28:44.861727331Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Short dcid=50d4f14a35c2210c key_phase=false len=6 pn=5 
[2025-09-12T06:28:44.861732040Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-09-12T06:28:44.861741497Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=117.428028ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 651380058 }, next_time: Instant { tv_sec: 643, tv_nsec: 654290948 }, max_datagram_size: 1350, last_packet_size: Some(32), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-09-12T06:28:44.861786191Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:45062 to 193.167.100.100:443
[2025-09-12T06:28:44.861795218Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Short dcid=50d4f14a35c2210c key_phase=false len=73 pn=6 
[2025-09-12T06:28:44.861799716Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-09-12T06:28:44.861809464Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=117.360011ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1452 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 651518586 }, next_time: Instant { tv_sec: 643, tv_nsec: 654290948 }, max_datagram_size: 1350, last_packet_size: None, iv: 304.603µs, 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-12T06:28:44.861848878Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:45062 to 193.167.100.100:443
[2025-09-12T06:28:44.861857835Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Short dcid=50d4f14a35c2210c key_phase=false len=37 pn=7 
[2025-09-12T06:28:44.861862593Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm STREAM id=4 off=0 len=32 fin=true
[2025-09-12T06:28:44.861872853Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=117.296563ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1515 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 651518586 }, next_time: Instant { tv_sec: 643, tv_nsec: 654595551 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-12T06:28:44.861916504Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:45062 to 193.167.100.100:443
[2025-09-12T06:28:44.861925591Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Short dcid=50d4f14a35c2210c key_phase=false len=37 pn=8 
[2025-09-12T06:28:44.861929669Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm STREAM id=8 off=0 len=32 fin=true
[2025-09-12T06:28:44.861939297Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=117.230319ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1578 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 651518586 }, next_time: Instant { tv_sec: 643, tv_nsec: 654595551 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-12T06:28:44.861977899Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:45062 to 193.167.100.100:443
[2025-09-12T06:28:44.861982929Z TRACE quiche_apps::client] 0.0.0.0:45062 -> 193.167.100.100:443: done writing
[2025-09-12T06:28:44.894608585Z TRACE quiche_apps::client] got 583 bytes from 193.167.100.100:443 to 0.0.0.0:45062
[2025-09-12T06:28:44.894624004Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx pkt Short dcid=9a21dcf5832f7325d1603be97c382a2ce2927487 key_phase=false len=562 pn=2 
[2025-09-12T06:28:44.894633060Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm CRYPTO off=0 len=540
[2025-09-12T06:28:44.894658708Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm HANDSHAKE_DONE
[2025-09-12T06:28:44.894663688Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 dropped epoch 1 state
[2025-09-12T06:28:44.894673426Z TRACE quiche_apps::client] 0.0.0.0:45062: processed 583 bytes
[2025-09-12T06:28:44.894678045Z TRACE quiche_apps::client] 0.0.0.0:45062: recv() would block
[2025-09-12T06:28:44.894681010Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:44.894690828Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Short dcid=50d4f14a35c2210c key_phase=false len=5 pn=9 
[2025-09-12T06:28:44.894694155Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm ACK delay=8 blocks=[2..2] ecn_counts=None
[2025-09-12T06:28:44.894702641Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=109.931192ms latest_rtt=39.237964ms srtt=39.237964ms min_rtt=39.237964ms rttvar=19.618982ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1479 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 650948331 }, end_of_app_limited: 7, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { bandwidth: 67.89 Kbps, is_app_limited: true, interval: 39.237964ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 611351286 }), send_elapsed: 0ns, ack_elapsed: 39.237964ms, rtt: 39.237964ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 430068, last_update: Instant { tv_sec: 643, tv_nsec: 684414227 }, next_time: Instant { tv_sec: 643, tv_nsec: 684414227 }, 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-12T06:28:44.894757243Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:45062 to 193.167.100.100:443
[2025-09-12T06:28:44.894762873Z TRACE quiche_apps::client] 0.0.0.0:45062 -> 193.167.100.100:443: done writing
[2025-09-12T06:28:44.894870234Z TRACE quiche_apps::client] got 314 bytes from 193.167.100.100:443 to 0.0.0.0:45062
[2025-09-12T06:28:44.894880984Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 dropped invalid packet
[2025-09-12T06:28:44.894886464Z TRACE quiche_apps::client] 0.0.0.0:45062: processed 314 bytes
[2025-09-12T06:28:44.894892545Z TRACE quiche_apps::client] 0.0.0.0:45062: recv() would block
[2025-09-12T06:28:44.894897134Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:44.894905059Z TRACE quiche_apps::client] 0.0.0.0:45062 -> 193.167.100.100:443: done writing
[2025-09-12T06:28:44.894916590Z TRACE quiche_apps::client] got 54 bytes from 193.167.100.100:443 to 0.0.0.0:45062
[2025-09-12T06:28:44.894923443Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx pkt Short dcid=9a21dcf5832f7325d1603be97c382a2ce2927487 key_phase=false len=33 pn=5 
[2025-09-12T06:28:44.894930657Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm ACK delay=18 blocks=[3..8] ecn_counts=None
[2025-09-12T06:28:44.894935015Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 packet newly acked 3
[2025-09-12T06:28:44.894939062Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 packet newly acked 4
[2025-09-12T06:28:44.894942358Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 packet newly acked 5
[2025-09-12T06:28:44.894945134Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 packet newly acked 6
[2025-09-12T06:28:44.894948159Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 packet newly acked 7
[2025-09-12T06:28:44.894950874Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 packet newly acked 8
[2025-09-12T06:28:44.894955533Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm STREAM id=0 off=0 len=0 fin=true
[2025-09-12T06:28:44.894959721Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm STREAM id=4 off=0 len=0 fin=true
[2025-09-12T06:28:44.894963197Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 rx frm STREAM id=8 off=0 len=0 fin=true
[2025-09-12T06:28:44.894981562Z TRACE quiche_apps::client] 0.0.0.0:45062: processed 54 bytes
[2025-09-12T06:28:44.894985910Z TRACE quiche_apps::client] 0.0.0.0:45062: recv() would block
[2025-09-12T06:28:44.894988765Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:44.894991610Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 stream id 0 is readable
[2025-09-12T06:28:44.894998523Z TRACE quiche::h3::stream] 9a21dcf5832f7325d1603be97c382a2ce2927487 read 0 bytes on stream 0
[2025-09-12T06:28:44.895002210Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 stream id 4 is readable
[2025-09-12T06:28:44.895006268Z TRACE quiche::h3::stream] 9a21dcf5832f7325d1603be97c382a2ce2927487 read 0 bytes on stream 4
[2025-09-12T06:28:44.895009173Z TRACE quiche::h3] 9a21dcf5832f7325d1603be97c382a2ce2927487 stream id 8 is readable
[2025-09-12T06:28:44.895013281Z TRACE quiche::h3::stream] 9a21dcf5832f7325d1603be97c382a2ce2927487 read 0 bytes on stream 8
[2025-09-12T06:28:44.895017008Z DEBUG quiche_apps::common] 1/3 responses received
[2025-09-12T06:28:44.895020475Z DEBUG quiche_apps::common] 2/3 responses received
[2025-09-12T06:28:44.895023089Z DEBUG quiche_apps::common] 3/3 responses received
[2025-09-12T06:28:44.895032056Z INFO  quiche_apps::common] 3/3 response(s) received in 73.283925ms, closing...
[2025-09-12T06:28:44.895040862Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx pkt Short dcid=50d4f14a35c2210c key_phase=false len=11 pn=10 
[2025-09-12T06:28:44.895044149Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T06:28:44.895052675Z TRACE quiche::recovery::congestion::recovery] 9a21dcf5832f7325d1603be97c382a2ce2927487 timer=131.114143ms latest_rtt=30.054777ms srtt=38.090065ms min_rtt=30.054777ms rttvar=17.010032ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1812, delivered_time: Instant { tv_sec: 643, tv_nsec: 684414227 }, first_sent_time: Instant { tv_sec: 643, tv_nsec: 684414227 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { bandwidth: 351.07 Kbps, is_app_limited: true, interval: 33.701997ms, delivered: 1479, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 643, tv_nsec: 650948331 }), send_elapsed: 3.64722ms, ack_elapsed: 33.701997ms, rtt: 30.054777ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 443028, last_update: Instant { tv_sec: 643, tv_nsec: 684765474 }, next_time: Instant { tv_sec: 643, tv_nsec: 684414227 }, max_datagram_size: 1350, last_packet_size: None, iv: 83.516µs, 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-12T06:28:44.895091708Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:45062 to 193.167.100.100:443
[2025-09-12T06:28:44.895096396Z TRACE quiche_apps::client] 0.0.0.0:45062 -> 193.167.100.100:443: done writing
[2025-09-12T06:28:45.214476009Z TRACE quiche_apps::client] timed out
[2025-09-12T06:28:45.214497108Z TRACE quiche] 9a21dcf5832f7325d1603be97c382a2ce2927487 draining timeout expired
[2025-09-12T06:28:45.214541721Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:45.214550628Z INFO  quiche_apps::client] connection closed, recv=4 sent=11 lost=0 retrans=0 sent_bytes=2040 recv_bytes=1889 lost_bytes=0 [local_addr=0.0.0.0:45062 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=4 sent=11 lost=0 retrans=0 rtt=38.090065ms min_rtt=Some(30.054777ms) rttvar=17.010032ms cwnd=13500 sent_bytes=2040 recv_bytes=1889 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=43884]