[2025-09-12T06:33:11.632167320Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T06:33:11.632487147Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:34970 with scid c7e2398687d023b064045fe2c14f2d6a50fab316
[2025-09-12T06:33:11.632533223Z TRACE quiche::tls] c7e2398687d023b064045fe2c14f2d6a50fab316 write message lvl=Initial len=266
[2025-09-12T06:33:11.632556567Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Initial version=1 dcid=83957a7a589f0f7fec31b7aa4f9d2b37 scid=c7e2398687d023b064045fe2c14f2d6a50fab316 len=270 pn=0 
[2025-09-12T06:33:11.632559963Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm CRYPTO off=0 len=266
[2025-09-12T06:33:11.632574891Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=998.921153ms 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: 910, tv_nsec: 422225073 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 422225073 }, 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: 910, tv_nsec: 422225073 }, next_time: Instant { tv_sec: 910, tv_nsec: 422225073 }, 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:33:11.632626567Z TRACE quiche_apps::client] written 1200
[2025-09-12T06:33:11.671512261Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:34970
[2025-09-12T06:33:11.671537017Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx pkt Initial version=1 dcid=c7e2398687d023b064045fe2c14f2d6a50fab316 scid=79cdd6f4fdceb29d token= len=117 pn=0 
[2025-09-12T06:33:11.671555521Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx frm ACK delay=150 blocks=[0..0] ecn_counts=None
[2025-09-12T06:33:11.671565370Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 packet newly acked 0
[2025-09-12T06:33:11.671573986Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx frm CRYPTO off=0 len=90
[2025-09-12T06:33:11.671727863Z TRACE quiche::tls] c7e2398687d023b064045fe2c14f2d6a50fab316 set write secret lvl=Handshake
[2025-09-12T06:33:11.671740557Z TRACE quiche::tls] c7e2398687d023b064045fe2c14f2d6a50fab316 set read secret lvl=Handshake
[2025-09-12T06:33:11.671772066Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx pkt Handshake version=1 dcid=c7e2398687d023b064045fe2c14f2d6a50fab316 scid=79cdd6f4fdceb29d len=1060 pn=1 
[2025-09-12T06:33:11.671780271Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx frm CRYPTO off=0 len=710
[2025-09-12T06:33:11.672016031Z TRACE quiche::tls] c7e2398687d023b064045fe2c14f2d6a50fab316 write message lvl=Handshake len=36
[2025-09-12T06:33:11.672020870Z TRACE quiche::tls] c7e2398687d023b064045fe2c14f2d6a50fab316 set write secret lvl=OneRTT
[2025-09-12T06:33:11.672029636Z TRACE quiche::tls] c7e2398687d023b064045fe2c14f2d6a50fab316 set read secret lvl=OneRTT
[2025-09-12T06:33:11.672048762Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 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(83957a7a589f0f7fec31b7aa4f9d2b37), max_idle_timeout: 30000, stateless_reset_token: Some(155857084596158353932166303766317354481), 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(79cdd6f4fdceb29d), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T06:33:11.672071495Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx frm PADDING len=329
[2025-09-12T06:33:11.672089168Z TRACE quiche_apps::client] 0.0.0.0:34970: processed 1252 bytes
[2025-09-12T06:33:11.672095529Z TRACE quiche_apps::client] 0.0.0.0:34970: recv() would block
[2025-09-12T06:33:11.672098785Z TRACE quiche_apps::client] done reading
[2025-09-12T06:33:11.672115657Z TRACE quiche::h3] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm SETTINGS stream=2 len=18
[2025-09-12T06:33:11.672134713Z TRACE quiche::h3] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm GREASE stream=0 len=0
[2025-09-12T06:33:11.672142247Z TRACE quiche::h3] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm GREASE stream=0 len=18
[2025-09-12T06:33:11.672151203Z TRACE quiche::h3] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm HEADERS stream=0 len=29 fin=true
[2025-09-12T06:33:11.672158757Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /oehzpolexn", "user-agent: quiche"]
[2025-09-12T06:33:11.672233988Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Initial version=1 dcid=79cdd6f4fdceb29d scid=c7e2398687d023b064045fe2c14f2d6a50fab316 len=6 pn=1 
[2025-09-12T06:33:11.672239167Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm ACK delay=87 blocks=[0..0] ecn_counts=None
[2025-09-12T06:33:11.672250449Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=116.390001ms latest_rtt=39.036094ms srtt=39.036094ms min_rtt=39.036094ms rttvar=19.518047ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.24 Kbps, is_app_limited: true, interval: 39.036094ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 910, tv_nsec: 422225073 }), send_elapsed: 0ns, ack_elapsed: 39.036094ms, rtt: 39.036094ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432292, last_update: Instant { tv_sec: 910, tv_nsec: 461775547 }, next_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, 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:33:11.672310751Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Handshake version=1 dcid=79cdd6f4fdceb29d scid=c7e2398687d023b064045fe2c14f2d6a50fab316 len=46 pn=2 
[2025-09-12T06:33:11.672321000Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm ACK delay=67 blocks=[1..1] ecn_counts=None
[2025-09-12T06:33:11.672325078Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm CRYPTO off=0 len=36
[2025-09-12T06:33:11.672334425Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=116.998567ms latest_rtt=39.036094ms srtt=39.036094ms min_rtt=39.036094ms rttvar=19.518047ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=100 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.24 Kbps, is_app_limited: true, interval: 39.036094ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 910, tv_nsec: 422225073 }), send_elapsed: 0ns, ack_elapsed: 39.036094ms, rtt: 39.036094ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432292, last_update: Instant { tv_sec: 910, tv_nsec: 461953619 }, next_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, max_datagram_size: 1350, last_packet_size: None, iv: 231.325µ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:33:11.672356937Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 dropped epoch 0 state
[2025-09-12T06:33:11.672374951Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Short dcid=79cdd6f4fdceb29d key_phase=false len=1163 pn=3 
[2025-09-12T06:33:11.672378507Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[29, 0f, 66, 67, a6, df, dd, dc, db, 3a, 77, 33, 75, e7, f8, 1e, 58, 20, 08, 71] reset_token=[12, fc, e9, f8, 15, 18, d5, fb, 2e, 20, d4, 4a, eb, 4b, 5c, a8]
[2025-09-12T06:33:11.672387304Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[2a, c1, a6, 7d, 7e, 41, c9, d5, 2f, 2a, c0, 5c, 82, 3c, 33, 20, a3, 3b, 1a, d6] reset_token=[16, 2b, 72, 80, 3e, 8c, fa, c6, df, b0, 9c, fd, f3, cb, 7e, c1]
[2025-09-12T06:33:11.672394277Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[d5, c9, a5, 71, c2, e7, 24, 36, 4c, a5, 8c, cf, d0, ce, 3f, 39, d8, 8b, 52, c9] reset_token=[68, c0, c7, 24, f6, be, 60, 6c, 0f, a4, 72, de, 94, 48, a5, a2]
[2025-09-12T06:33:11.672401090Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[29, ff, 13, 31, 3a, 79, a0, e1, 02, 40, 09, 8e, 68, bd, 7f, 81, 97, dd, f7, 05] reset_token=[8d, 1e, fc, f9, 22, 26, 2d, 7f, 64, bd, df, 83, 1d, d4, db, f5]
[2025-09-12T06:33:11.672407932Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[79, df, 24, 25, c2, 77, 8a, 30, 72, e2, b8, c6, 68, f7, 81, 4a, f0, bb, f8, a8] reset_token=[60, 7f, 6c, c3, f0, 95, 70, dc, 76, b5, f7, 4c, 56, db, 18, a2]
[2025-09-12T06:33:11.672414515Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[df, b8, cc, 0b, 02, 00, 7a, f9, d8, 28, a5, 63, e3, da, 1b, 5f, 96, 5a, 0b, e3] reset_token=[8e, 4f, 43, 5a, ef, fa, 9b, d6, 58, 49, 09, 16, 88, e8, 8f, 93]
[2025-09-12T06:33:11.672421437Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[66, d6, 61, 45, ad, 48, 25, 71, 55, 87, 23, 2f, e6, eb, a8, ff, 15, 0b, 38, 04] reset_token=[ed, 4f, 89, d7, ad, 78, 6f, ce, 53, 42, 1e, 4a, cd, be, 21, 3d]
[2025-09-12T06:33:11.672428240Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-09-12T06:33:11.672431667Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm PADDING len=859
[2025-09-12T06:33:11.672462705Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=116.870197ms latest_rtt=39.036094ms srtt=39.036094ms min_rtt=39.036094ms rttvar=19.518047ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.24 Kbps, is_app_limited: true, interval: 39.036094ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 910, tv_nsec: 422225073 }), send_elapsed: 0ns, ack_elapsed: 39.036094ms, rtt: 39.036094ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1189, rate: 432292, last_update: Instant { tv_sec: 910, tv_nsec: 461953619 }, next_time: Instant { tv_sec: 910, tv_nsec: 462184944 }, max_datagram_size: 1350, last_packet_size: Some(1189), 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:33:11.672515032Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:34970 to 193.167.100.100:443
[2025-09-12T06:33:11.672523839Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Short dcid=79cdd6f4fdceb29d key_phase=false len=6 pn=4 
[2025-09-12T06:33:11.672527786Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-09-12T06:33:11.672537224Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=116.795488ms latest_rtt=39.036094ms srtt=39.036094ms min_rtt=39.036094ms rttvar=19.518047ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.24 Kbps, is_app_limited: true, interval: 39.036094ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 910, tv_nsec: 422225073 }), send_elapsed: 0ns, ack_elapsed: 39.036094ms, rtt: 39.036094ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432292, last_update: Instant { tv_sec: 910, tv_nsec: 462248309 }, next_time: Instant { tv_sec: 910, tv_nsec: 462184944 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.82448ms, 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:33:11.672581897Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:34970 to 193.167.100.100:443
[2025-09-12T06:33:11.672594350Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Short dcid=79cdd6f4fdceb29d key_phase=false len=6 pn=5 
[2025-09-12T06:33:11.672599069Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-09-12T06:33:11.672608466Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=116.724255ms latest_rtt=39.036094ms srtt=39.036094ms min_rtt=39.036094ms rttvar=19.518047ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.24 Kbps, is_app_limited: true, interval: 39.036094ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 910, tv_nsec: 422225073 }), send_elapsed: 0ns, ack_elapsed: 39.036094ms, rtt: 39.036094ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 432292, last_update: Instant { tv_sec: 910, tv_nsec: 462248309 }, next_time: Instant { tv_sec: 910, tv_nsec: 465141848 }, 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:33:11.672654262Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:34970 to 193.167.100.100:443
[2025-09-12T06:33:11.672662838Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Short dcid=79cdd6f4fdceb29d key_phase=false len=72 pn=6 
[2025-09-12T06:33:11.672666114Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm STREAM id=0 off=0 len=67 fin=true
[2025-09-12T06:33:11.672676213Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=116.656478ms latest_rtt=39.036094ms srtt=39.036094ms min_rtt=39.036094ms rttvar=19.518047ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1451 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.24 Kbps, is_app_limited: true, interval: 39.036094ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 910, tv_nsec: 422225073 }), send_elapsed: 0ns, ack_elapsed: 39.036094ms, rtt: 39.036094ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432292, last_update: Instant { tv_sec: 910, tv_nsec: 462386908 }, next_time: Instant { tv_sec: 910, tv_nsec: 465141848 }, max_datagram_size: 1350, last_packet_size: None, iv: 300.723µ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:33:11.672717200Z TRACE quiche_apps::client] written 98 bytes from 0.0.0.0:34970 to 193.167.100.100:443
[2025-09-12T06:33:11.672723221Z TRACE quiche_apps::client] 0.0.0.0:34970 -> 193.167.100.100:443: done writing
[2025-09-12T06:33:11.705414223Z TRACE quiche_apps::client] got 583 bytes from 193.167.100.100:443 to 0.0.0.0:34970
[2025-09-12T06:33:11.705426566Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx pkt Short dcid=c7e2398687d023b064045fe2c14f2d6a50fab316 key_phase=false len=562 pn=2 
[2025-09-12T06:33:11.705437175Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx frm CRYPTO off=0 len=540
[2025-09-12T06:33:11.705467202Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx frm HANDSHAKE_DONE
[2025-09-12T06:33:11.705473854Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 dropped epoch 1 state
[2025-09-12T06:33:11.705484614Z TRACE quiche_apps::client] 0.0.0.0:34970: processed 583 bytes
[2025-09-12T06:33:11.705490635Z TRACE quiche_apps::client] 0.0.0.0:34970: recv() would block
[2025-09-12T06:33:11.705494763Z TRACE quiche_apps::client] done reading
[2025-09-12T06:33:11.705507016Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Short dcid=79cdd6f4fdceb29d key_phase=false len=5 pn=7 
[2025-09-12T06:33:11.705511214Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm ACK delay=9 blocks=[2..2] ecn_counts=None
[2025-09-12T06:33:11.705523897Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=109.241752ms latest_rtt=39.036094ms srtt=39.036094ms min_rtt=39.036094ms rttvar=19.518047ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1351 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 461775577 }, end_of_app_limited: 5, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.24 Kbps, is_app_limited: true, interval: 39.036094ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 910, tv_nsec: 422225073 }), send_elapsed: 0ns, ack_elapsed: 39.036094ms, rtt: 39.036094ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432292, last_update: Instant { tv_sec: 910, tv_nsec: 495229553 }, next_time: Instant { tv_sec: 910, tv_nsec: 495530276 }, 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:33:11.705592435Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34970 to 193.167.100.100:443
[2025-09-12T06:33:11.705602985Z TRACE quiche_apps::client] 0.0.0.0:34970 -> 193.167.100.100:443: done writing
[2025-09-12T06:33:11.705728603Z TRACE quiche_apps::client] got 314 bytes from 193.167.100.100:443 to 0.0.0.0:34970
[2025-09-12T06:33:11.705742579Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 dropped invalid packet
[2025-09-12T06:33:11.705748019Z TRACE quiche_apps::client] 0.0.0.0:34970: processed 314 bytes
[2025-09-12T06:33:11.705755623Z TRACE quiche_apps::client] 0.0.0.0:34970: recv() would block
[2025-09-12T06:33:11.705760693Z TRACE quiche_apps::client] done reading
[2025-09-12T06:33:11.705770271Z TRACE quiche_apps::client] 0.0.0.0:34970 -> 193.167.100.100:443: done writing
[2025-09-12T06:33:11.705805556Z TRACE quiche_apps::client] got 48 bytes from 193.167.100.100:443 to 0.0.0.0:34970
[2025-09-12T06:33:11.705815124Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx pkt Short dcid=c7e2398687d023b064045fe2c14f2d6a50fab316 key_phase=false len=27 pn=5 
[2025-09-12T06:33:11.705827427Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx frm ACK delay=15 blocks=[3..6] ecn_counts=None
[2025-09-12T06:33:11.705835001Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 packet newly acked 3
[2025-09-12T06:33:11.705841193Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 packet newly acked 4
[2025-09-12T06:33:11.705846222Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 packet newly acked 5
[2025-09-12T06:33:11.705851502Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 packet newly acked 6
[2025-09-12T06:33:11.705857303Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 rx frm STREAM id=0 off=0 len=0 fin=true
[2025-09-12T06:33:11.705876789Z TRACE quiche_apps::client] 0.0.0.0:34970: processed 48 bytes
[2025-09-12T06:33:11.705882079Z TRACE quiche_apps::client] 0.0.0.0:34970: recv() would block
[2025-09-12T06:33:11.705885155Z TRACE quiche_apps::client] done reading
[2025-09-12T06:33:11.705888331Z TRACE quiche::h3] c7e2398687d023b064045fe2c14f2d6a50fab316 stream id 0 is readable
[2025-09-12T06:33:11.705897067Z TRACE quiche::h3::stream] c7e2398687d023b064045fe2c14f2d6a50fab316 read 0 bytes on stream 0
[2025-09-12T06:33:11.705903309Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-12T06:33:11.705909010Z INFO  quiche_apps::common] 1/1 response(s) received in 73.278906ms, closing...
[2025-09-12T06:33:11.705922645Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx pkt Short dcid=79cdd6f4fdceb29d key_phase=false len=11 pn=8 
[2025-09-12T06:33:11.705928215Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T06:33:11.705942001Z TRACE quiche::recovery::congestion::recovery] c7e2398687d023b064045fe2c14f2d6a50fab316 timer=130.121245ms latest_rtt=30.399771ms srtt=37.956553ms min_rtt=30.399771ms rttvar=16.797615ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1684, delivered_time: Instant { tv_sec: 910, tv_nsec: 495530276 }, first_sent_time: Instant { tv_sec: 910, tv_nsec: 495530276 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 6, rate_sample: RateSample { bandwidth: 320.08 Kbps, is_app_limited: true, interval: 33.766042ms, delivered: 1351, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 910, tv_nsec: 461775577 }), send_elapsed: 3.366271ms, ack_elapsed: 33.766042ms, rtt: 30.399771ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 444587, last_update: Instant { tv_sec: 910, tv_nsec: 495645553 }, next_time: Instant { tv_sec: 910, tv_nsec: 495530276 }, max_datagram_size: 1350, last_packet_size: None, iv: 83.223µ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:33:11.706028963Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:34970 to 193.167.100.100:443
[2025-09-12T06:33:11.706039273Z TRACE quiche_apps::client] 0.0.0.0:34970 -> 193.167.100.100:443: done writing
[2025-09-12T06:33:12.022418797Z TRACE quiche_apps::client] timed out
[2025-09-12T06:33:12.022442642Z TRACE quiche] c7e2398687d023b064045fe2c14f2d6a50fab316 draining timeout expired
[2025-09-12T06:33:12.022485481Z TRACE quiche_apps::client] done reading
[2025-09-12T06:33:12.022492564Z INFO  quiche_apps::client] connection closed, recv=4 sent=9 lost=0 retrans=0 sent_bytes=1913 recv_bytes=1883 lost_bytes=0 [local_addr=0.0.0.0:34970 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=4 sent=9 lost=0 retrans=0 rtt=37.956553ms min_rtt=Some(30.399771ms) rttvar=16.797615ms cwnd=13500 sent_bytes=1913 recv_bytes=1883 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=40010]