[2025-09-11T18:58:44.741112330Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-11T18:58:44.741397352Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:47564 with scid 8a246221899333220e46603682c8679a4e5ada12
[2025-09-11T18:58:44.741437587Z TRACE quiche::tls] 8a246221899333220e46603682c8679a4e5ada12 write message lvl=Initial len=266
[2025-09-11T18:58:44.741454909Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Initial version=1 dcid=e727b83b2617ba3fb8a10b78ed01ee9e scid=8a246221899333220e46603682c8679a4e5ada12 len=270 pn=0 
[2025-09-11T18:58:44.741457995Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm CRYPTO off=0 len=266
[2025-09-11T18:58:44.741471891Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=998.931842ms 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: 740, tv_nsec: 654576554 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 654576554 }, 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: 740, tv_nsec: 654576554 }, next_time: Instant { tv_sec: 740, tv_nsec: 654576554 }, 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-11T18:58:44.741526823Z TRACE quiche_apps::client] written 1200
[2025-09-11T18:58:44.780277292Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:47564
[2025-09-11T18:58:44.780296869Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx pkt Initial version=1 dcid=8a246221899333220e46603682c8679a4e5ada12 scid=53840b3ab23efa6a token= len=117 pn=0 
[2025-09-11T18:58:44.780309673Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx frm ACK delay=137 blocks=[0..0] ecn_counts=None
[2025-09-11T18:58:44.780316255Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 packet newly acked 0
[2025-09-11T18:58:44.780322336Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx frm CRYPTO off=0 len=90
[2025-09-11T18:58:44.780433003Z TRACE quiche::tls] 8a246221899333220e46603682c8679a4e5ada12 set write secret lvl=Handshake
[2025-09-11T18:58:44.780449103Z TRACE quiche::tls] 8a246221899333220e46603682c8679a4e5ada12 set read secret lvl=Handshake
[2025-09-11T18:58:44.780481493Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx pkt Handshake version=1 dcid=8a246221899333220e46603682c8679a4e5ada12 scid=53840b3ab23efa6a len=1060 pn=1 
[2025-09-11T18:58:44.780488817Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx frm CRYPTO off=0 len=709
[2025-09-11T18:58:44.780729406Z TRACE quiche::tls] 8a246221899333220e46603682c8679a4e5ada12 write message lvl=Handshake len=36
[2025-09-11T18:58:44.780734415Z TRACE quiche::tls] 8a246221899333220e46603682c8679a4e5ada12 set write secret lvl=OneRTT
[2025-09-11T18:58:44.780743152Z TRACE quiche::tls] 8a246221899333220e46603682c8679a4e5ada12 set read secret lvl=OneRTT
[2025-09-11T18:58:44.780761907Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 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(e727b83b2617ba3fb8a10b78ed01ee9e), max_idle_timeout: 30000, stateless_reset_token: Some(301282363651729600275025802773245683586), 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(53840b3ab23efa6a), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-11T18:58:44.780785371Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx frm PADDING len=330
[2025-09-11T18:58:44.780803184Z TRACE quiche_apps::client] 0.0.0.0:47564: processed 1252 bytes
[2025-09-11T18:58:44.780809215Z TRACE quiche_apps::client] 0.0.0.0:47564: recv() would block
[2025-09-11T18:58:44.780812551Z TRACE quiche_apps::client] done reading
[2025-09-11T18:58:44.780857175Z TRACE quiche::h3] 8a246221899333220e46603682c8679a4e5ada12 tx frm SETTINGS stream=2 len=18
[2025-09-11T18:58:44.780881681Z TRACE quiche::h3] 8a246221899333220e46603682c8679a4e5ada12 tx frm GREASE stream=0 len=0
[2025-09-11T18:58:44.780889154Z TRACE quiche::h3] 8a246221899333220e46603682c8679a4e5ada12 tx frm GREASE stream=0 len=18
[2025-09-11T18:58:44.780897911Z TRACE quiche::h3] 8a246221899333220e46603682c8679a4e5ada12 tx frm HEADERS stream=0 len=30 fin=true
[2025-09-11T18:58:44.780905495Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /yvjccbbidh", "user-agent: quiche"]
[2025-09-11T18:58:44.780980795Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Initial version=1 dcid=53840b3ab23efa6a scid=8a246221899333220e46603682c8679a4e5ada12 len=6 pn=1 
[2025-09-11T18:58:44.780985514Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm ACK delay=85 blocks=[0..0] ecn_counts=None
[2025-09-11T18:58:44.780997166Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=115.961033ms latest_rtt=38.888577ms srtt=38.888577ms min_rtt=38.888577ms rttvar=19.444288ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.50 Kbps, is_app_limited: true, interval: 38.888577ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 740, tv_nsec: 654576554 }), send_elapsed: 0ns, ack_elapsed: 38.888577ms, rtt: 38.888577ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433932, last_update: Instant { tv_sec: 740, tv_nsec: 693932213 }, next_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, 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-11T18:58:44.781020810Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Handshake version=1 dcid=53840b3ab23efa6a scid=8a246221899333220e46603682c8679a4e5ada12 len=46 pn=2 
[2025-09-11T18:58:44.781024667Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm ACK delay=67 blocks=[1..1] ecn_counts=None
[2025-09-11T18:58:44.781028094Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm CRYPTO off=0 len=36
[2025-09-11T18:58:44.781035648Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=116.601729ms latest_rtt=38.888577ms srtt=38.888577ms min_rtt=38.888577ms rttvar=19.444288ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=100 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.50 Kbps, is_app_limited: true, interval: 38.888577ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 740, tv_nsec: 654576554 }), send_elapsed: 0ns, ack_elapsed: 38.888577ms, rtt: 38.888577ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433932, last_update: Instant { tv_sec: 740, tv_nsec: 694143988 }, next_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, max_datagram_size: 1350, last_packet_size: None, iv: 230.451µ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-11T18:58:44.781056256Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 dropped epoch 0 state
[2025-09-11T18:58:44.781072617Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Short dcid=53840b3ab23efa6a key_phase=false len=1163 pn=3 
[2025-09-11T18:58:44.781076124Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[d7, 32, c8, 73, 2f, f9, 21, 05, e7, d0, d0, f7, b8, 4d, a4, d5, f5, ce, 95, 4f] reset_token=[1d, 31, 14, d5, 3f, cb, 7d, 44, f4, 33, 9f, c3, ac, 0b, f7, 7b]
[2025-09-11T18:58:44.781084529Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[b6, 5b, 70, 3e, d5, f8, 25, 55, 4d, a9, e0, a0, 88, 77, 67, 99, d0, 29, cb, 3d] reset_token=[ee, 66, 52, 96, 82, 48, 77, ef, 8d, 00, d6, a6, e3, e1, b5, d5]
[2025-09-11T18:58:44.781091192Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[eb, d1, 90, f2, 6d, 26, a8, 93, b8, b0, 7e, 11, 77, 43, ea, 97, 00, 9e, 1b, 38] reset_token=[88, 53, 23, fd, d8, 3f, 4d, 2e, 7a, 98, 45, 2d, 21, 0f, b7, 08]
[2025-09-11T18:58:44.781097674Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[79, 00, cf, 5e, c4, fc, b3, 8a, 2a, 52, c7, d7, 16, 3e, 8d, fe, 55, 08, e3, 38] reset_token=[c7, 8b, dd, cb, e0, 97, ea, 02, 8d, b8, 9a, 17, 46, b7, d5, 33]
[2025-09-11T18:58:44.781104186Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[fc, 80, 42, be, 5f, 8b, 49, 26, 8d, 81, a4, 0b, f4, 22, 12, 50, 00, dd, ee, b3] reset_token=[31, 41, d7, 21, 32, 05, c6, 91, c2, d3, 94, 0b, 36, 03, 64, 6d]
[2025-09-11T18:58:44.781111049Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[34, 64, a5, e9, 5e, 77, d5, 7c, 10, 89, 15, 1c, 1a, 83, 6a, 2b, 37, 50, 57, 39] reset_token=[23, 01, 77, 5c, 06, 86, 2c, ba, d8, 06, b7, 28, e0, 28, 98, 00]
[2025-09-11T18:58:44.781118172Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[9f, 9c, fb, ae, c3, 82, 02, 41, 26, de, df, 89, 35, f8, 86, 16, de, 0a, d8, 8b] reset_token=[55, ba, f4, e7, 5e, 7e, 50, 84, e4, b0, d9, bb, 7c, 28, 63, bd]
[2025-09-11T18:58:44.781125165Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-09-11T18:58:44.781128671Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm PADDING len=859
[2025-09-11T18:58:44.781161884Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=116.475353ms latest_rtt=38.888577ms srtt=38.888577ms min_rtt=38.888577ms rttvar=19.444288ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.50 Kbps, is_app_limited: true, interval: 38.888577ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 740, tv_nsec: 654576554 }), send_elapsed: 0ns, ack_elapsed: 38.888577ms, rtt: 38.888577ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1189, rate: 433932, last_update: Instant { tv_sec: 740, tv_nsec: 694143988 }, next_time: Instant { tv_sec: 740, tv_nsec: 694374439 }, 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-11T18:58:44.781209993Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:47564 to 193.167.100.100:443
[2025-09-11T18:58:44.781222587Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Short dcid=53840b3ab23efa6a key_phase=false len=6 pn=4 
[2025-09-11T18:58:44.781227095Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-09-11T18:58:44.781236653Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=116.400363ms latest_rtt=38.888577ms srtt=38.888577ms min_rtt=38.888577ms rttvar=19.444288ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.50 Kbps, is_app_limited: true, interval: 38.888577ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 740, tv_nsec: 654576554 }), send_elapsed: 0ns, ack_elapsed: 38.888577ms, rtt: 38.888577ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433932, last_update: Instant { tv_sec: 740, tv_nsec: 694388565 }, next_time: Instant { tv_sec: 740, tv_nsec: 694374439 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.813805ms, 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-11T18:58:44.781272720Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:47564 to 193.167.100.100:443
[2025-09-11T18:58:44.781283180Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Short dcid=53840b3ab23efa6a key_phase=false len=6 pn=5 
[2025-09-11T18:58:44.781287378Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-09-11T18:58:44.781296234Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=116.340752ms latest_rtt=38.888577ms srtt=38.888577ms min_rtt=38.888577ms rttvar=19.444288ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.50 Kbps, is_app_limited: true, interval: 38.888577ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 740, tv_nsec: 654576554 }), send_elapsed: 0ns, ack_elapsed: 38.888577ms, rtt: 38.888577ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 433932, last_update: Instant { tv_sec: 740, tv_nsec: 694388565 }, next_time: Instant { tv_sec: 740, tv_nsec: 697264055 }, 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-11T18:58:44.781334967Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:47564 to 193.167.100.100:443
[2025-09-11T18:58:44.781346198Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Short dcid=53840b3ab23efa6a key_phase=false len=73 pn=6 
[2025-09-11T18:58:44.781350536Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-09-11T18:58:44.781359502Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=116.277404ms latest_rtt=38.888577ms srtt=38.888577ms min_rtt=38.888577ms rttvar=19.444288ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1452 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.50 Kbps, is_app_limited: true, interval: 38.888577ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 740, tv_nsec: 654576554 }), send_elapsed: 0ns, ack_elapsed: 38.888577ms, rtt: 38.888577ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433932, last_update: Instant { tv_sec: 740, tv_nsec: 694512566 }, next_time: Instant { tv_sec: 740, tv_nsec: 697264055 }, max_datagram_size: 1350, last_packet_size: None, iv: 301.891µ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-11T18:58:44.781391061Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:47564 to 193.167.100.100:443
[2025-09-11T18:58:44.781397634Z TRACE quiche_apps::client] 0.0.0.0:47564 -> 193.167.100.100:443: done writing
[2025-09-11T18:58:44.814133755Z TRACE quiche_apps::client] got 583 bytes from 193.167.100.100:443 to 0.0.0.0:47564
[2025-09-11T18:58:44.814150276Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx pkt Short dcid=8a246221899333220e46603682c8679a4e5ada12 key_phase=false len=562 pn=2 
[2025-09-11T18:58:44.814160635Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx frm CRYPTO off=0 len=540
[2025-09-11T18:58:44.814188758Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx frm HANDSHAKE_DONE
[2025-09-11T18:58:44.814194088Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 dropped epoch 1 state
[2025-09-11T18:58:44.814204788Z TRACE quiche_apps::client] 0.0.0.0:47564: processed 583 bytes
[2025-09-11T18:58:44.814209867Z TRACE quiche_apps::client] 0.0.0.0:47564: recv() would block
[2025-09-11T18:58:44.814213023Z TRACE quiche_apps::client] done reading
[2025-09-11T18:58:44.814224825Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Short dcid=53840b3ab23efa6a key_phase=false len=5 pn=7 
[2025-09-11T18:58:44.814228552Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm ACK delay=9 blocks=[2..2] ecn_counts=None
[2025-09-11T18:58:44.814238781Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=108.766903ms latest_rtt=38.888577ms srtt=38.888577ms min_rtt=38.888577ms rttvar=19.444288ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1352 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 693932253 }, end_of_app_limited: 5, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.50 Kbps, is_app_limited: true, interval: 38.888577ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 740, tv_nsec: 654576554 }), send_elapsed: 0ns, ack_elapsed: 38.888577ms, rtt: 38.888577ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433932, last_update: Instant { tv_sec: 740, tv_nsec: 727390493 }, next_time: Instant { tv_sec: 740, tv_nsec: 727692384 }, 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-11T18:58:44.814299284Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:47564 to 193.167.100.100:443
[2025-09-11T18:58:44.814310074Z TRACE quiche_apps::client] 0.0.0.0:47564 -> 193.167.100.100:443: done writing
[2025-09-11T18:58:44.814359957Z TRACE quiche_apps::client] got 314 bytes from 193.167.100.100:443 to 0.0.0.0:47564
[2025-09-11T18:58:44.814366510Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 dropped invalid packet
[2025-09-11T18:58:44.814370297Z TRACE quiche_apps::client] 0.0.0.0:47564: processed 314 bytes
[2025-09-11T18:58:44.814375216Z TRACE quiche_apps::client] got 48 bytes from 193.167.100.100:443 to 0.0.0.0:47564
[2025-09-11T18:58:44.814379444Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx pkt Short dcid=8a246221899333220e46603682c8679a4e5ada12 key_phase=false len=27 pn=5 
[2025-09-11T18:58:44.814386808Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx frm ACK delay=17 blocks=[3..6] ecn_counts=None
[2025-09-11T18:58:44.814391366Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 packet newly acked 3
[2025-09-11T18:58:44.814395353Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 packet newly acked 4
[2025-09-11T18:58:44.814398690Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 packet newly acked 5
[2025-09-11T18:58:44.814401585Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 packet newly acked 6
[2025-09-11T18:58:44.814406484Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 rx frm STREAM id=0 off=0 len=0 fin=true
[2025-09-11T18:58:44.814423726Z TRACE quiche_apps::client] 0.0.0.0:47564: processed 48 bytes
[2025-09-11T18:58:44.814428395Z TRACE quiche_apps::client] 0.0.0.0:47564: recv() would block
[2025-09-11T18:58:44.814431571Z TRACE quiche_apps::client] done reading
[2025-09-11T18:58:44.814434757Z TRACE quiche::h3] 8a246221899333220e46603682c8679a4e5ada12 stream id 0 is readable
[2025-09-11T18:58:44.814441560Z TRACE quiche::h3::stream] 8a246221899333220e46603682c8679a4e5ada12 read 0 bytes on stream 0
[2025-09-11T18:58:44.814445778Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-11T18:58:44.814448953Z INFO  quiche_apps::common] 1/1 response(s) received in 72.91676ms, closing...
[2025-09-11T18:58:44.814458081Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx pkt Short dcid=53840b3ab23efa6a key_phase=false len=11 pn=8 
[2025-09-11T18:58:44.814461517Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-11T18:58:44.814470183Z TRACE quiche::recovery::congestion::recovery] 8a246221899333220e46603682c8679a4e5ada12 timer=129.732022ms latest_rtt=30.286436ms srtt=37.813308ms min_rtt=30.286436ms rttvar=16.733751ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1685, delivered_time: Instant { tv_sec: 740, tv_nsec: 727692384 }, first_sent_time: Instant { tv_sec: 740, tv_nsec: 727692384 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 6, rate_sample: RateSample { bandwidth: 321.73 Kbps, is_app_limited: true, interval: 33.618238ms, delivered: 1352, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 740, tv_nsec: 693932253 }), send_elapsed: 3.331802ms, ack_elapsed: 33.618238ms, rtt: 30.286436ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 446271, last_update: Instant { tv_sec: 740, tv_nsec: 727626343 }, next_time: Instant { tv_sec: 740, tv_nsec: 727692384 }, max_datagram_size: 1350, last_packet_size: None, iv: 82.909µ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-11T18:58:44.814522170Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:47564 to 193.167.100.100:443
[2025-09-11T18:58:44.814530866Z TRACE quiche_apps::client] 0.0.0.0:47564 -> 193.167.100.100:443: done writing
[2025-09-11T18:58:45.129879747Z TRACE quiche_apps::client] timed out
[2025-09-11T18:58:45.129899815Z TRACE quiche] 8a246221899333220e46603682c8679a4e5ada12 draining timeout expired
[2025-09-11T18:58:45.129942314Z TRACE quiche_apps::client] done reading
[2025-09-11T18:58:45.129949137Z INFO  quiche_apps::client] connection closed, recv=4 sent=9 lost=0 retrans=0 sent_bytes=1914 recv_bytes=1883 lost_bytes=0 [local_addr=0.0.0.0:47564 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=4 sent=9 lost=0 retrans=0 rtt=37.813308ms min_rtt=Some(30.286436ms) rttvar=16.733751ms cwnd=13500 sent_bytes=1914 recv_bytes=1883 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=40216]