[2025-09-12T06:30:47.655884616Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T06:30:47.656170240Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:40569 with scid 9812c03c15cff4216401e6e00096f85646e76c11
[2025-09-12T06:30:47.656210014Z TRACE quiche::tls] 9812c03c15cff4216401e6e00096f85646e76c11 write message lvl=Initial len=266
[2025-09-12T06:30:47.656226485Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Initial version=1 dcid=5b8fdd108594bd967cdceff5b45a43f4 scid=9812c03c15cff4216401e6e00096f85646e76c11 len=270 pn=0 
[2025-09-12T06:30:47.656229601Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm CRYPTO off=0 len=266
[2025-09-12T06:30:47.656243988Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=998.932874ms 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: 766, tv_nsec: 445905891 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 445905891 }, 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: 766, tv_nsec: 445905891 }, next_time: Instant { tv_sec: 766, tv_nsec: 445905891 }, 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:30:47.656316674Z TRACE quiche_apps::client] written 1200
[2025-09-12T06:30:47.695085801Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40569
[2025-09-12T06:30:47.695099566Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx pkt Initial version=1 dcid=9812c03c15cff4216401e6e00096f85646e76c11 scid=7ec5ec92e1b4b9d6 token= len=117 pn=0 
[2025-09-12T06:30:47.695109535Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx frm ACK delay=138 blocks=[0..0] ecn_counts=None
[2025-09-12T06:30:47.695114254Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 packet newly acked 0
[2025-09-12T06:30:47.695118632Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx frm CRYPTO off=0 len=90
[2025-09-12T06:30:47.695200815Z TRACE quiche::tls] 9812c03c15cff4216401e6e00096f85646e76c11 set write secret lvl=Handshake
[2025-09-12T06:30:47.695213339Z TRACE quiche::tls] 9812c03c15cff4216401e6e00096f85646e76c11 set read secret lvl=Handshake
[2025-09-12T06:30:47.695237163Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx pkt Handshake version=1 dcid=9812c03c15cff4216401e6e00096f85646e76c11 scid=7ec5ec92e1b4b9d6 len=1060 pn=1 
[2025-09-12T06:30:47.695242714Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx frm CRYPTO off=0 len=709
[2025-09-12T06:30:47.695447496Z TRACE quiche::tls] 9812c03c15cff4216401e6e00096f85646e76c11 write message lvl=Handshake len=36
[2025-09-12T06:30:47.695451845Z TRACE quiche::tls] 9812c03c15cff4216401e6e00096f85646e76c11 set write secret lvl=OneRTT
[2025-09-12T06:30:47.695458807Z TRACE quiche::tls] 9812c03c15cff4216401e6e00096f85646e76c11 set read secret lvl=OneRTT
[2025-09-12T06:30:47.695473625Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 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(5b8fdd108594bd967cdceff5b45a43f4), max_idle_timeout: 30000, stateless_reset_token: Some(223260359285888994498558332797325019001), 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(7ec5ec92e1b4b9d6), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T06:30:47.695491198Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx frm PADDING len=330
[2025-09-12T06:30:47.695505465Z TRACE quiche_apps::client] 0.0.0.0:40569: processed 1252 bytes
[2025-09-12T06:30:47.695510163Z TRACE quiche_apps::client] 0.0.0.0:40569: recv() would block
[2025-09-12T06:30:47.695512598Z TRACE quiche_apps::client] done reading
[2025-09-12T06:30:47.695526725Z TRACE quiche::h3] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm SETTINGS stream=2 len=18
[2025-09-12T06:30:47.695541632Z TRACE quiche::h3] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm GREASE stream=0 len=0
[2025-09-12T06:30:47.695547243Z TRACE quiche::h3] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm GREASE stream=0 len=18
[2025-09-12T06:30:47.695554256Z TRACE quiche::h3] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm HEADERS stream=0 len=30 fin=true
[2025-09-12T06:30:47.695560227Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /gqdsjhvwxg", "user-agent: quiche"]
[2025-09-12T06:30:47.695617965Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Initial version=1 dcid=7ec5ec92e1b4b9d6 scid=9812c03c15cff4216401e6e00096f85646e76c11 len=6 pn=1 
[2025-09-12T06:30:47.695621822Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm ACK delay=64 blocks=[0..0] ecn_counts=None
[2025-09-12T06:30:47.695630288Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=116.225588ms latest_rtt=38.919719ms srtt=38.919719ms min_rtt=38.919719ms rttvar=19.459859ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.45 Kbps, is_app_limited: true, interval: 38.919719ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 766, tv_nsec: 445905891 }), send_elapsed: 0ns, ack_elapsed: 38.919719ms, rtt: 38.919719ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433584, last_update: Instant { tv_sec: 766, tv_nsec: 485200701 }, next_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, 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:30:47.695648372Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Handshake version=1 dcid=7ec5ec92e1b4b9d6 scid=9812c03c15cff4216401e6e00096f85646e76c11 len=45 pn=2 
[2025-09-12T06:30:47.695651297Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm ACK delay=51 blocks=[1..1] ecn_counts=None
[2025-09-12T06:30:47.695653892Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm CRYPTO off=0 len=36
[2025-09-12T06:30:47.695659603Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=116.710574ms latest_rtt=38.919719ms srtt=38.919719ms min_rtt=38.919719ms rttvar=19.459859ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.45 Kbps, is_app_limited: true, interval: 38.919719ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 766, tv_nsec: 445905891 }), send_elapsed: 0ns, ack_elapsed: 38.919719ms, rtt: 38.919719ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433584, last_update: Instant { tv_sec: 766, tv_nsec: 485339741 }, next_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, max_datagram_size: 1350, last_packet_size: None, iv: 228.329µ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:30:47.695675653Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 dropped epoch 0 state
[2025-09-12T06:30:47.695688737Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Short dcid=7ec5ec92e1b4b9d6 key_phase=false len=1164 pn=3 
[2025-09-12T06:30:47.695691452Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[d2, 2b, 0c, c0, f0, f4, fd, c1, 35, d7, ce, 17, ae, a9, 65, c4, b7, 59, 3d, d0] reset_token=[5c, c4, 0c, 2d, 12, 8a, 8d, 95, ca, af, fb, d0, f6, 86, 3b, 8b]
[2025-09-12T06:30:47.695698586Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[d9, 49, 1e, 2e, 2c, f7, 18, e2, 26, 6a, 73, 06, b3, 44, 47, ae, 7a, 7d, 99, aa] reset_token=[31, 2d, e9, c1, ec, 81, 09, 7e, b4, 89, d9, a7, 59, 6d, 27, 7c]
[2025-09-12T06:30:47.695704116Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[01, 03, fa, 42, 48, 48, e9, c2, dd, 98, 3a, 5c, fa, d2, 54, f4, b6, c1, a9, 0e] reset_token=[b5, 7a, b6, 19, 25, da, 16, 80, 55, c7, 34, 4f, 1e, a8, 74, 7c]
[2025-09-12T06:30:47.695709606Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[98, 25, 16, 70, e1, 60, 54, 70, c0, b3, 9f, 41, c1, bb, e3, 71, 99, f9, ea, a4] reset_token=[95, f8, 34, 33, bb, ae, ca, e2, a2, 9a, 01, 2d, ae, a1, 33, 65]
[2025-09-12T06:30:47.695714856Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[62, b3, fc, be, 7b, 2b, 67, be, b4, 6e, 50, c1, 59, ec, 1d, b5, 04, 0c, 5b, d6] reset_token=[65, e1, 5f, 99, fb, b6, f7, 69, 37, 53, 4a, 49, c3, 7f, bb, c9]
[2025-09-12T06:30:47.695720136Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[51, 0e, 74, 31, b5, aa, ab, 52, de, ac, 3a, bc, 55, f1, a6, e1, 5c, 4c, 8b, 23] reset_token=[ea, 13, eb, 97, 82, d8, 33, ad, 7a, c4, f1, 52, 01, 99, fe, ff]
[2025-09-12T06:30:47.695725406Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, df, 7f, b6, 87, 93, 69, 16, a7, 6b, 37, 23, 5d, 93, 04, 88, b9, 2a, 4d, 24] reset_token=[44, ed, ee, 79, 72, 61, 37, ec, 98, bf, ba, 73, ed, d5, a9, 23]
[2025-09-12T06:30:47.695730606Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-09-12T06:30:47.695733140Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm PADDING len=860
[2025-09-12T06:30:47.695756143Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=116.613914ms latest_rtt=38.919719ms srtt=38.919719ms min_rtt=38.919719ms rttvar=19.459859ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.45 Kbps, is_app_limited: true, interval: 38.919719ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 766, tv_nsec: 445905891 }), send_elapsed: 0ns, ack_elapsed: 38.919719ms, rtt: 38.919719ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1190, rate: 433584, last_update: Instant { tv_sec: 766, tv_nsec: 485339741 }, next_time: Instant { tv_sec: 766, tv_nsec: 485568070 }, 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:30:47.695795296Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:40569 to 193.167.100.100:443
[2025-09-12T06:30:47.695803451Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Short dcid=7ec5ec92e1b4b9d6 key_phase=false len=6 pn=4 
[2025-09-12T06:30:47.695806087Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-09-12T06:30:47.695811497Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=116.558711ms latest_rtt=38.919719ms srtt=38.919719ms min_rtt=38.919719ms rttvar=19.459859ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.45 Kbps, is_app_limited: true, interval: 38.919719ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 766, tv_nsec: 445905891 }), send_elapsed: 0ns, ack_elapsed: 38.919719ms, rtt: 38.919719ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433584, last_update: Instant { tv_sec: 766, tv_nsec: 485527812 }, next_time: Instant { tv_sec: 766, tv_nsec: 485568070 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.81837ms, 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:30:47.695836794Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:40569 to 193.167.100.100:443
[2025-09-12T06:30:47.695842324Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Short dcid=7ec5ec92e1b4b9d6 key_phase=false len=6 pn=5 
[2025-09-12T06:30:47.695844789Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-09-12T06:30:47.695849477Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=116.52072ms latest_rtt=38.919719ms srtt=38.919719ms min_rtt=38.919719ms rttvar=19.459859ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.45 Kbps, is_app_limited: true, interval: 38.919719ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 766, tv_nsec: 445905891 }), send_elapsed: 0ns, ack_elapsed: 38.919719ms, rtt: 38.919719ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 433584, last_update: Instant { tv_sec: 766, tv_nsec: 485527812 }, next_time: Instant { tv_sec: 766, tv_nsec: 488386678 }, 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:30:47.695876608Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:40569 to 193.167.100.100:443
[2025-09-12T06:30:47.695882058Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Short dcid=7ec5ec92e1b4b9d6 key_phase=false len=73 pn=6 
[2025-09-12T06:30:47.695884573Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-09-12T06:30:47.695889532Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=116.480695ms latest_rtt=38.919719ms srtt=38.919719ms min_rtt=38.919719ms rttvar=19.459859ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1452 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.45 Kbps, is_app_limited: true, interval: 38.919719ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 766, tv_nsec: 445905891 }), send_elapsed: 0ns, ack_elapsed: 38.919719ms, rtt: 38.919719ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433584, last_update: Instant { tv_sec: 766, tv_nsec: 485608082 }, next_time: Instant { tv_sec: 766, tv_nsec: 488386678 }, max_datagram_size: 1350, last_packet_size: None, iv: 302.133µ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:30:47.695908909Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:40569 to 193.167.100.100:443
[2025-09-12T06:30:47.695912135Z TRACE quiche_apps::client] 0.0.0.0:40569 -> 193.167.100.100:443: done writing
[2025-09-12T06:30:47.728858929Z TRACE quiche_apps::client] got 583 bytes from 193.167.100.100:443 to 0.0.0.0:40569
[2025-09-12T06:30:47.728879608Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx pkt Short dcid=9812c03c15cff4216401e6e00096f85646e76c11 key_phase=false len=562 pn=2 
[2025-09-12T06:30:47.728891069Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx frm CRYPTO off=0 len=540
[2025-09-12T06:30:47.728913431Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx frm HANDSHAKE_DONE
[2025-09-12T06:30:47.728924281Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 dropped epoch 1 state
[2025-09-12T06:30:47.728934150Z TRACE quiche_apps::client] 0.0.0.0:40569: processed 583 bytes
[2025-09-12T06:30:47.728937977Z TRACE quiche_apps::client] 0.0.0.0:40569: recv() would block
[2025-09-12T06:30:47.728940471Z TRACE quiche_apps::client] done reading
[2025-09-12T06:30:47.728948947Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Short dcid=7ec5ec92e1b4b9d6 key_phase=false len=5 pn=7 
[2025-09-12T06:30:47.728951752Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm ACK delay=9 blocks=[2..2] ecn_counts=None
[2025-09-12T06:30:47.728959798Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=108.67855ms latest_rtt=38.919719ms srtt=38.919719ms min_rtt=38.919719ms rttvar=19.459859ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 485200731 }, end_of_app_limited: 5, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.45 Kbps, is_app_limited: true, interval: 38.919719ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 766, tv_nsec: 445905891 }), send_elapsed: 0ns, ack_elapsed: 38.919719ms, rtt: 38.919719ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 433584, last_update: Instant { tv_sec: 766, tv_nsec: 518672837 }, next_time: Instant { tv_sec: 766, tv_nsec: 518974970 }, 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:30:47.729005643Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40569 to 193.167.100.100:443
[2025-09-12T06:30:47.729013718Z TRACE quiche_apps::client] 0.0.0.0:40569 -> 193.167.100.100:443: done writing
[2025-09-12T06:30:47.729116811Z TRACE quiche_apps::client] got 314 bytes from 193.167.100.100:443 to 0.0.0.0:40569
[2025-09-12T06:30:47.729121510Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 dropped invalid packet
[2025-09-12T06:30:47.729124295Z TRACE quiche_apps::client] 0.0.0.0:40569: processed 314 bytes
[2025-09-12T06:30:47.729127441Z TRACE quiche_apps::client] 0.0.0.0:40569: recv() would block
[2025-09-12T06:30:47.729129755Z TRACE quiche_apps::client] done reading
[2025-09-12T06:30:47.729133863Z TRACE quiche_apps::client] 0.0.0.0:40569 -> 193.167.100.100:443: done writing
[2025-09-12T06:30:47.729156505Z TRACE quiche_apps::client] got 48 bytes from 193.167.100.100:443 to 0.0.0.0:40569
[2025-09-12T06:30:47.729160212Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx pkt Short dcid=9812c03c15cff4216401e6e00096f85646e76c11 key_phase=false len=27 pn=5 
[2025-09-12T06:30:47.729165883Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx frm ACK delay=16 blocks=[3..6] ecn_counts=None
[2025-09-12T06:30:47.729169369Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 packet newly acked 3
[2025-09-12T06:30:47.729172385Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 packet newly acked 4
[2025-09-12T06:30:47.729174869Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 packet newly acked 5
[2025-09-12T06:30:47.729177104Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 packet newly acked 6
[2025-09-12T06:30:47.729180720Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 rx frm STREAM id=0 off=0 len=0 fin=true
[2025-09-12T06:30:47.729194156Z TRACE quiche_apps::client] 0.0.0.0:40569: processed 48 bytes
[2025-09-12T06:30:47.729197602Z TRACE quiche_apps::client] 0.0.0.0:40569: recv() would block
[2025-09-12T06:30:47.729199866Z TRACE quiche_apps::client] done reading
[2025-09-12T06:30:47.729202131Z TRACE quiche::h3] 9812c03c15cff4216401e6e00096f85646e76c11 stream id 0 is readable
[2025-09-12T06:30:47.729207450Z TRACE quiche::h3::stream] 9812c03c15cff4216401e6e00096f85646e76c11 read 0 bytes on stream 0
[2025-09-12T06:30:47.729210857Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-12T06:30:47.729213632Z INFO  quiche_apps::common] 1/1 response(s) received in 72.889725ms, closing...
[2025-09-12T06:30:47.729220545Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx pkt Short dcid=7ec5ec92e1b4b9d6 key_phase=false len=11 pn=8 
[2025-09-12T06:30:47.729223230Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T06:30:47.729229892Z TRACE quiche::recovery::congestion::recovery] 9812c03c15cff4216401e6e00096f85646e76c11 timer=129.653008ms latest_rtt=30.501161ms srtt=37.867399ms min_rtt=30.501161ms rttvar=16.699533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1686, delivered_time: Instant { tv_sec: 766, tv_nsec: 518974970 }, first_sent_time: Instant { tv_sec: 766, tv_nsec: 518974970 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 6, rate_sample: RateSample { bandwidth: 321.30 Kbps, is_app_limited: true, interval: 33.687108ms, delivered: 1353, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 766, tv_nsec: 485200731 }), send_elapsed: 3.185947ms, ack_elapsed: 33.687108ms, rtt: 30.501161ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 445633, last_update: Instant { tv_sec: 766, tv_nsec: 518946168 }, next_time: Instant { tv_sec: 766, tv_nsec: 518974970 }, max_datagram_size: 1350, last_packet_size: None, iv: 83.028µ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:30:47.729300644Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:40569 to 193.167.100.100:443
[2025-09-12T06:30:47.729311335Z TRACE quiche_apps::client] 0.0.0.0:40569 -> 193.167.100.100:443: done writing
[2025-09-12T06:30:48.043683036Z TRACE quiche_apps::client] timed out
[2025-09-12T06:30:48.043702442Z TRACE quiche] 9812c03c15cff4216401e6e00096f85646e76c11 draining timeout expired
[2025-09-12T06:30:48.043734863Z TRACE quiche_apps::client] done reading
[2025-09-12T06:30:48.043740964Z 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:40569 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=4 sent=9 lost=0 retrans=0 rtt=37.867399ms min_rtt=Some(30.501161ms) rttvar=16.699533ms cwnd=13500 sent_bytes=1914 recv_bytes=1883 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=40163]