[2025-09-12T06:28:32.161776896Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T06:28:32.162077708Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:56468 with scid 71ad27e334928852fb7ee20882136f01f640be16
[2025-09-12T06:28:32.162117723Z TRACE quiche::tls] 71ad27e334928852fb7ee20882136f01f640be16 write message lvl=Initial len=266
[2025-09-12T06:28:32.162134675Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Initial version=1 dcid=0e751195efa0351d000b47bc00f6ad65 scid=71ad27e334928852fb7ee20882136f01f640be16 len=270 pn=0 
[2025-09-12T06:28:32.162137911Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm CRYPTO off=0 len=266
[2025-09-12T06:28:32.162151987Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=998.932353ms 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: 630, tv_nsec: 951813440 }, first_sent_time: Instant { tv_sec: 630, tv_nsec: 951813440 }, 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: 630, tv_nsec: 951813440 }, next_time: Instant { tv_sec: 630, tv_nsec: 951813440 }, 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:32.162207230Z TRACE quiche_apps::client] written 1200
[2025-09-12T06:28:32.201065759Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:56468
[2025-09-12T06:28:32.201082050Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx pkt Initial version=1 dcid=71ad27e334928852fb7ee20882136f01f640be16 scid=822fdff43a21879d token= len=117 pn=0 
[2025-09-12T06:28:32.201093632Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx frm ACK delay=150 blocks=[0..0] ecn_counts=None
[2025-09-12T06:28:32.201102378Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 packet newly acked 0
[2025-09-12T06:28:32.201112026Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx frm CRYPTO off=0 len=90
[2025-09-12T06:28:32.201211642Z TRACE quiche::tls] 71ad27e334928852fb7ee20882136f01f640be16 set write secret lvl=Handshake
[2025-09-12T06:28:32.201222012Z TRACE quiche::tls] 71ad27e334928852fb7ee20882136f01f640be16 set read secret lvl=Handshake
[2025-09-12T06:28:32.201250134Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx pkt Handshake version=1 dcid=71ad27e334928852fb7ee20882136f01f640be16 scid=822fdff43a21879d len=1060 pn=1 
[2025-09-12T06:28:32.201256696Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx frm CRYPTO off=0 len=709
[2025-09-12T06:28:32.201514759Z TRACE quiche::tls] 71ad27e334928852fb7ee20882136f01f640be16 write message lvl=Handshake len=36
[2025-09-12T06:28:32.201520490Z TRACE quiche::tls] 71ad27e334928852fb7ee20882136f01f640be16 set write secret lvl=OneRTT
[2025-09-12T06:28:32.201527513Z TRACE quiche::tls] 71ad27e334928852fb7ee20882136f01f640be16 set read secret lvl=OneRTT
[2025-09-12T06:28:32.201542822Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 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(0e751195efa0351d000b47bc00f6ad65), max_idle_timeout: 30000, stateless_reset_token: Some(257256999965316855006902851255058564467), 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(822fdff43a21879d), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T06:28:32.201561226Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx frm PADDING len=330
[2025-09-12T06:28:32.201576064Z TRACE quiche_apps::client] 0.0.0.0:56468: processed 1252 bytes
[2025-09-12T06:28:32.201580702Z TRACE quiche_apps::client] 0.0.0.0:56468: recv() would block
[2025-09-12T06:28:32.201583127Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:32.201597925Z TRACE quiche::h3] 71ad27e334928852fb7ee20882136f01f640be16 tx frm SETTINGS stream=2 len=18
[2025-09-12T06:28:32.201613133Z TRACE quiche::h3] 71ad27e334928852fb7ee20882136f01f640be16 tx frm GREASE stream=0 len=0
[2025-09-12T06:28:32.201621068Z TRACE quiche::h3] 71ad27e334928852fb7ee20882136f01f640be16 tx frm GREASE stream=0 len=18
[2025-09-12T06:28:32.201630936Z TRACE quiche::h3] 71ad27e334928852fb7ee20882136f01f640be16 tx frm HEADERS stream=0 len=30 fin=true
[2025-09-12T06:28:32.201636887Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /gjmkmbogrn", "user-agent: quiche"]
[2025-09-12T06:28:32.201694245Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Initial version=1 dcid=822fdff43a21879d scid=71ad27e334928852fb7ee20882136f01f640be16 len=6 pn=1 
[2025-09-12T06:28:32.201697941Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm ACK delay=76 blocks=[0..0] ecn_counts=None
[2025-09-12T06:28:32.201706918Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=116.354983ms latest_rtt=38.994333ms srtt=38.994333ms min_rtt=38.994333ms rttvar=19.497166ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, first_sent_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.31 Kbps, is_app_limited: true, interval: 38.994333ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 630, tv_nsec: 951813440 }), send_elapsed: 0ns, ack_elapsed: 38.994333ms, rtt: 38.994333ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432755, last_update: Instant { tv_sec: 630, tv_nsec: 991269887 }, next_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, 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:32.201726064Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Handshake version=1 dcid=822fdff43a21879d scid=71ad27e334928852fb7ee20882136f01f640be16 len=45 pn=2 
[2025-09-12T06:28:32.201729090Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm ACK delay=59 blocks=[1..1] ecn_counts=None
[2025-09-12T06:28:32.201731635Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm CRYPTO off=0 len=36
[2025-09-12T06:28:32.201737235Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=116.932893ms latest_rtt=38.994333ms srtt=38.994333ms min_rtt=38.994333ms rttvar=19.497166ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, first_sent_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.31 Kbps, is_app_limited: true, interval: 38.994333ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 630, tv_nsec: 951813440 }), send_elapsed: 0ns, ack_elapsed: 38.994333ms, rtt: 38.994333ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432755, last_update: Instant { tv_sec: 630, tv_nsec: 991415830 }, next_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, max_datagram_size: 1350, last_packet_size: None, iv: 228.767µ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:32.201753796Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 dropped epoch 0 state
[2025-09-12T06:28:32.201766560Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Short dcid=822fdff43a21879d key_phase=false len=1164 pn=3 
[2025-09-12T06:28:32.201769145Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[75, 16, ff, 8c, 55, 73, 97, 22, 8f, 7c, 3d, 5f, 04, c0, 91, 5c, 81, 59, 32, 16] reset_token=[2d, 4c, 59, 73, 83, 5d, f5, d2, 2b, 55, 37, 7a, 92, 56, 73, 69]
[2025-09-12T06:28:32.201775877Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[c8, 56, df, 49, e5, 14, 1e, 5e, 97, 4a, db, a7, 3d, cb, cc, c8, 77, ca, 73, 2d] reset_token=[be, 07, 32, d5, 77, 35, ee, b5, 8e, 9b, 69, 14, bf, e7, f0, d4]
[2025-09-12T06:28:32.201781618Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[e2, 86, f3, 8b, 03, c7, 93, 07, 1a, 7e, ee, 4f, 79, 5f, 67, 55, 12, 91, fa, 57] reset_token=[60, 3d, d7, c5, e0, e8, 4d, 9b, bb, 9a, 17, 1a, 44, 4f, ef, f7]
[2025-09-12T06:28:32.201787268Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[72, 8b, 8a, af, 58, a7, 48, a0, 43, 4c, 62, 23, 9a, d8, 3c, ac, bd, c5, 41, 42] reset_token=[32, ec, d2, 7a, 70, ff, 88, 25, d4, fb, f1, 8d, a1, bf, 69, f6]
[2025-09-12T06:28:32.201792678Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[fc, eb, 3d, c7, 5d, a2, 06, 3d, b9, 57, f2, e5, 2d, 01, 10, 9d, 83, ad, a8, 8d] reset_token=[55, ab, 8f, de, 02, 5c, 40, 23, 1f, ba, 9a, a5, 6f, 55, c1, bd]
[2025-09-12T06:28:32.201798329Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[f3, 9a, f5, 0e, 40, d0, 7f, 38, 35, 82, 64, cb, 42, fc, 3f, b8, b3, 96, 4c, 1c] reset_token=[89, 4a, f0, 6e, 34, 9e, 67, 80, a7, cd, c2, eb, 6e, 3b, 8a, cb]
[2025-09-12T06:28:32.201803830Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[ed, ec, 8a, 84, 5f, b1, b9, e7, 9a, c7, 14, 48, 8a, ab, 68, de, e4, 41, ed, 09] reset_token=[69, 98, 23, 9a, e3, a7, 3c, 2f, ce, e9, e0, 22, 65, 0f, 11, 05]
[2025-09-12T06:28:32.201809410Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-09-12T06:28:32.201811965Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm PADDING len=860
[2025-09-12T06:28:32.201835118Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=116.83489ms latest_rtt=38.994333ms srtt=38.994333ms min_rtt=38.994333ms rttvar=19.497166ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, first_sent_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.31 Kbps, is_app_limited: true, interval: 38.994333ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 630, tv_nsec: 951813440 }), send_elapsed: 0ns, ack_elapsed: 38.994333ms, rtt: 38.994333ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1190, rate: 432755, last_update: Instant { tv_sec: 630, tv_nsec: 991415830 }, next_time: Instant { tv_sec: 630, tv_nsec: 991644597 }, 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:32.201877096Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:56468 to 193.167.100.100:443
[2025-09-12T06:28:32.201889640Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Short dcid=822fdff43a21879d key_phase=false len=6 pn=4 
[2025-09-12T06:28:32.201894188Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-09-12T06:28:32.201903456Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=116.766052ms latest_rtt=38.994333ms srtt=38.994333ms min_rtt=38.994333ms rttvar=19.497166ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, first_sent_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.31 Kbps, is_app_limited: true, interval: 38.994333ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 630, tv_nsec: 951813440 }), send_elapsed: 0ns, ack_elapsed: 38.994333ms, rtt: 38.994333ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432755, last_update: Instant { tv_sec: 630, tv_nsec: 991612458 }, next_time: Instant { tv_sec: 630, tv_nsec: 991644597 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.823769ms, 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:32.201941016Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:56468 to 193.167.100.100:443
[2025-09-12T06:28:32.201951666Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Short dcid=822fdff43a21879d key_phase=false len=6 pn=5 
[2025-09-12T06:28:32.201955834Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-09-12T06:28:32.201964660Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=116.705037ms latest_rtt=38.994333ms srtt=38.994333ms min_rtt=38.994333ms rttvar=19.497166ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, first_sent_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.31 Kbps, is_app_limited: true, interval: 38.994333ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 630, tv_nsec: 951813440 }), send_elapsed: 0ns, ack_elapsed: 38.994333ms, rtt: 38.994333ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 432755, last_update: Instant { tv_sec: 630, tv_nsec: 991612458 }, next_time: Instant { tv_sec: 630, tv_nsec: 994498994 }, 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:32.202003904Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:56468 to 193.167.100.100:443
[2025-09-12T06:28:32.202014874Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Short dcid=822fdff43a21879d key_phase=false len=73 pn=6 
[2025-09-12T06:28:32.202018892Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-09-12T06:28:32.202028099Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=116.641328ms latest_rtt=38.994333ms srtt=38.994333ms min_rtt=38.994333ms rttvar=19.497166ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1452 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, first_sent_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.31 Kbps, is_app_limited: true, interval: 38.994333ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 630, tv_nsec: 951813440 }), send_elapsed: 0ns, ack_elapsed: 38.994333ms, rtt: 38.994333ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432755, last_update: Instant { tv_sec: 630, tv_nsec: 991738213 }, next_time: Instant { tv_sec: 630, tv_nsec: 994498994 }, max_datagram_size: 1350, last_packet_size: None, iv: 302.712µ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:32.202060529Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:56468 to 193.167.100.100:443
[2025-09-12T06:28:32.202067292Z TRACE quiche_apps::client] 0.0.0.0:56468 -> 193.167.100.100:443: done writing
[2025-09-12T06:28:32.235036043Z TRACE quiche_apps::client] got 583 bytes from 193.167.100.100:443 to 0.0.0.0:56468
[2025-09-12T06:28:32.235052854Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx pkt Short dcid=71ad27e334928852fb7ee20882136f01f640be16 key_phase=false len=562 pn=2 
[2025-09-12T06:28:32.235063263Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx frm CRYPTO off=0 len=540
[2025-09-12T06:28:32.235090905Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx frm HANDSHAKE_DONE
[2025-09-12T06:28:32.235096345Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 dropped epoch 1 state
[2025-09-12T06:28:32.235106965Z TRACE quiche_apps::client] 0.0.0.0:56468: processed 583 bytes
[2025-09-12T06:28:32.235112115Z TRACE quiche_apps::client] 0.0.0.0:56468: recv() would block
[2025-09-12T06:28:32.235115311Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:32.235126372Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Short dcid=822fdff43a21879d key_phase=false len=5 pn=7 
[2025-09-12T06:28:32.235130108Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm ACK delay=9 blocks=[2..2] ecn_counts=None
[2025-09-12T06:28:32.235140258Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=108.851903ms latest_rtt=38.994333ms srtt=38.994333ms min_rtt=38.994333ms rttvar=19.497166ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, first_sent_time: Instant { tv_sec: 630, tv_nsec: 991269917 }, end_of_app_limited: 5, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.31 Kbps, is_app_limited: true, interval: 38.994333ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 630, tv_nsec: 951813440 }), send_elapsed: 0ns, ack_elapsed: 38.994333ms, rtt: 38.994333ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 432755, last_update: Instant { tv_sec: 631, tv_nsec: 24849169 }, next_time: Instant { tv_sec: 631, tv_nsec: 25151881 }, 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:32.235202274Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:56468 to 193.167.100.100:443
[2025-09-12T06:28:32.235212913Z TRACE quiche_apps::client] 0.0.0.0:56468 -> 193.167.100.100:443: done writing
[2025-09-12T06:28:32.235225978Z TRACE quiche_apps::client] got 314 bytes from 193.167.100.100:443 to 0.0.0.0:56468
[2025-09-12T06:28:32.235233061Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 dropped invalid packet
[2025-09-12T06:28:32.235239052Z TRACE quiche_apps::client] 0.0.0.0:56468: processed 314 bytes
[2025-09-12T06:28:32.235246737Z TRACE quiche_apps::client] got 48 bytes from 193.167.100.100:443 to 0.0.0.0:56468
[2025-09-12T06:28:32.235251626Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx pkt Short dcid=71ad27e334928852fb7ee20882136f01f640be16 key_phase=false len=27 pn=5 
[2025-09-12T06:28:32.235258629Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx frm ACK delay=16 blocks=[3..6] ecn_counts=None
[2025-09-12T06:28:32.235263317Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 packet newly acked 3
[2025-09-12T06:28:32.235267175Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 packet newly acked 4
[2025-09-12T06:28:32.235270521Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 packet newly acked 5
[2025-09-12T06:28:32.235305967Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 packet newly acked 6
[2025-09-12T06:28:32.235312249Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 rx frm STREAM id=0 off=0 len=0 fin=true
[2025-09-12T06:28:32.235330674Z TRACE quiche_apps::client] 0.0.0.0:56468: processed 48 bytes
[2025-09-12T06:28:32.235335783Z TRACE quiche_apps::client] 0.0.0.0:56468: recv() would block
[2025-09-12T06:28:32.235338959Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:32.235342185Z TRACE quiche::h3] 71ad27e334928852fb7ee20882136f01f640be16 stream id 0 is readable
[2025-09-12T06:28:32.235349429Z TRACE quiche::h3::stream] 71ad27e334928852fb7ee20882136f01f640be16 read 0 bytes on stream 0
[2025-09-12T06:28:32.235353686Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-12T06:28:32.235356903Z INFO  quiche_apps::common] 1/1 response(s) received in 73.144141ms, closing...
[2025-09-12T06:28:32.235366691Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx pkt Short dcid=822fdff43a21879d key_phase=false len=11 pn=8 
[2025-09-12T06:28:32.235370197Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T06:28:32.235379074Z TRACE quiche::recovery::congestion::recovery] 71ad27e334928852fb7ee20882136f01f640be16 timer=129.919012ms latest_rtt=30.479958ms srtt=37.930035ms min_rtt=30.479958ms rttvar=16.751467ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1686, delivered_time: Instant { tv_sec: 631, tv_nsec: 25151881 }, first_sent_time: Instant { tv_sec: 631, tv_nsec: 25151881 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 6, rate_sample: RateSample { bandwidth: 321.10 Kbps, is_app_limited: true, interval: 33.709035ms, delivered: 1353, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 630, tv_nsec: 991269917 }), send_elapsed: 3.229077ms, ack_elapsed: 33.709035ms, rtt: 30.479958ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 444898, last_update: Instant { tv_sec: 631, tv_nsec: 25091162 }, next_time: Instant { tv_sec: 631, tv_nsec: 25151881 }, max_datagram_size: 1350, last_packet_size: None, iv: 83.165µ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:32.235432564Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:56468 to 193.167.100.100:443
[2025-09-12T06:28:32.235441190Z TRACE quiche_apps::client] 0.0.0.0:56468 -> 193.167.100.100:443: done writing
[2025-09-12T06:28:32.550813086Z TRACE quiche_apps::client] timed out
[2025-09-12T06:28:32.550829496Z TRACE quiche] 71ad27e334928852fb7ee20882136f01f640be16 draining timeout expired
[2025-09-12T06:28:32.550861186Z TRACE quiche_apps::client] done reading
[2025-09-12T06:28:32.550867638Z 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:56468 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=4 sent=9 lost=0 retrans=0 rtt=37.930035ms min_rtt=Some(30.479958ms) rttvar=16.751467ms cwnd=13500 sent_bytes=1914 recv_bytes=1883 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=40137]