[2025-09-12T06:29:55.259411487Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T06:29:55.259700407Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:36811 with scid 58a183f046ec4e9dfab80ffd1e8f610010b14bb5
[2025-09-12T06:29:55.259744089Z TRACE quiche::tls] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 write message lvl=Initial len=266
[2025-09-12T06:29:55.259760429Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Initial version=1 dcid=24e778edcf59e99718e0bf10433db2a2 scid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 len=270 pn=0 
[2025-09-12T06:29:55.259763495Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm CRYPTO off=0 len=266
[2025-09-12T06:29:55.259778262Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=998.928436ms 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: 714, tv_nsec: 49435738 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 49435738 }, 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: 714, tv_nsec: 49435738 }, next_time: Instant { tv_sec: 714, tv_nsec: 49435738 }, 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:29:55.259828326Z TRACE quiche_apps::client] written 1200
[2025-09-12T06:29:55.296473562Z TRACE quiche_apps::client] got 111 bytes from 193.167.100.100:443 to 0.0.0.0:36811
[2025-09-12T06:29:55.296492147Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx pkt Retry version=1 dcid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 scid=c829965ed6602578 token=0001f0f6d394e9142520c84b3b347c77293f5a848318d950f9116d1896a71107b197d9a624118a154492d889fc035288f9a436fb5e90b4209f93604d
[2025-09-12T06:29:55.296517805Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 dropped epoch 0 state
[2025-09-12T06:29:55.296525790Z TRACE quiche_apps::client] 0.0.0.0:36811: processed 111 bytes
[2025-09-12T06:29:55.296530549Z TRACE quiche_apps::client] 0.0.0.0:36811: recv() would block
[2025-09-12T06:29:55.296533574Z TRACE quiche_apps::client] done reading
[2025-09-12T06:29:55.296574961Z TRACE quiche::tls] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 write message lvl=Initial len=266
[2025-09-12T06:29:55.296589719Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Initial version=1 dcid=c829965ed6602578 scid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 token=0001f0f6d394e9142520c84b3b347c77293f5a848318d950f9116d1896a71107b197d9a624118a154492d889fc035288f9a436fb5e90b4209f93604d len=270 pn=1 
[2025-09-12T06:29:55.296593466Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm CRYPTO off=0 len=266
[2025-09-12T06:29:55.296603535Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=998.937824ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=385 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 714, tv_nsec: 86270328 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 86270328 }, end_of_app_limited: 1, last_sent_packet: 1, 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: 714, tv_nsec: 86270328 }, next_time: Instant { tv_sec: 714, tv_nsec: 86270328 }, 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:29:55.296659299Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:36811 to 193.167.100.100:443
[2025-09-12T06:29:55.296666142Z TRACE quiche_apps::client] 0.0.0.0:36811 -> 193.167.100.100:443: done writing
[2025-09-12T06:29:55.330225556Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:36811
[2025-09-12T06:29:55.330241185Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx pkt Initial version=1 dcid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 scid=11862e0f279de7c0 token= len=117 pn=0 
[2025-09-12T06:29:55.330252667Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx frm ACK delay=126 blocks=[1..1] ecn_counts=None
[2025-09-12T06:29:55.330261664Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 packet newly acked 1
[2025-09-12T06:29:55.330270360Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx frm CRYPTO off=0 len=90
[2025-09-12T06:29:55.330400654Z TRACE quiche::tls] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 set write secret lvl=Handshake
[2025-09-12T06:29:55.330411163Z TRACE quiche::tls] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 set read secret lvl=Handshake
[2025-09-12T06:29:55.330438895Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx pkt Handshake version=1 dcid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 scid=11862e0f279de7c0 len=1060 pn=1 
[2025-09-12T06:29:55.330446279Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx frm CRYPTO off=0 len=720
[2025-09-12T06:29:55.330645381Z TRACE quiche::tls] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 write message lvl=Handshake len=36
[2025-09-12T06:29:55.330649138Z TRACE quiche::tls] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 set write secret lvl=OneRTT
[2025-09-12T06:29:55.330655780Z TRACE quiche::tls] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 set read secret lvl=OneRTT
[2025-09-12T06:29:55.330670828Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 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(24e778edcf59e99718e0bf10433db2a2), max_idle_timeout: 30000, stateless_reset_token: Some(215377291536408013125635935827390426032), 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(11862e0f279de7c0), retry_source_connection_id: Some(c829965ed6602578), max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T06:29:55.330681508Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx frm PADDING len=319
[2025-09-12T06:29:55.330694643Z TRACE quiche_apps::client] 0.0.0.0:36811: processed 1252 bytes
[2025-09-12T06:29:55.330699101Z TRACE quiche_apps::client] 0.0.0.0:36811: recv() would block
[2025-09-12T06:29:55.330701476Z TRACE quiche_apps::client] done reading
[2025-09-12T06:29:55.330714801Z TRACE quiche::h3] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm SETTINGS stream=2 len=18
[2025-09-12T06:29:55.330729378Z TRACE quiche::h3] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm GREASE stream=0 len=0
[2025-09-12T06:29:55.330735038Z TRACE quiche::h3] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm GREASE stream=0 len=18
[2025-09-12T06:29:55.330742021Z TRACE quiche::h3] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm HEADERS stream=0 len=29 fin=true
[2025-09-12T06:29:55.330752551Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /ztuczyiaab", "user-agent: quiche"]
[2025-09-12T06:29:55.330811030Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Initial version=1 dcid=11862e0f279de7c0 scid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 token=0001f0f6d394e9142520c84b3b347c77293f5a848318d950f9116d1896a71107b197d9a624118a154492d889fc035288f9a436fb5e90b4209f93604d len=6 pn=2 
[2025-09-12T06:29:55.330814888Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm ACK delay=71 blocks=[0..0] ecn_counts=None
[2025-09-12T06:29:55.330823434Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=100.505837ms latest_rtt=33.696961ms srtt=33.696961ms min_rtt=33.696961ms rttvar=16.84848ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 385, delivered_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { bandwidth: 91.40 Kbps, is_app_limited: true, interval: 33.696961ms, delivered: 385, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 86270328 }), send_elapsed: 0ns, ack_elapsed: 33.696961ms, rtt: 33.696961ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 500786, last_update: Instant { tv_sec: 714, tv_nsec: 120397724 }, next_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, 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:29:55.330841878Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Handshake version=1 dcid=11862e0f279de7c0 scid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 len=45 pn=3 
[2025-09-12T06:29:55.330844874Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm ACK delay=50 blocks=[1..1] ecn_counts=None
[2025-09-12T06:29:55.330847569Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm CRYPTO off=0 len=36
[2025-09-12T06:29:55.330853300Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=101.039776ms latest_rtt=33.696961ms srtt=33.696961ms min_rtt=33.696961ms rttvar=16.84848ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 385, delivered_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { bandwidth: 91.40 Kbps, is_app_limited: true, interval: 33.696961ms, delivered: 385, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 86270328 }), send_elapsed: 0ns, ack_elapsed: 33.696961ms, rtt: 33.696961ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 500786, last_update: Instant { tv_sec: 714, tv_nsec: 120530913 }, next_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, max_datagram_size: 1350, last_packet_size: None, iv: 197.689µ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:29:55.330864460Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 dropped epoch 0 state
[2025-09-12T06:29:55.330876573Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Short dcid=11862e0f279de7c0 key_phase=false len=1104 pn=4 
[2025-09-12T06:29:55.330879178Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[2e, d3, cc, c7, fd, d9, ea, 09, de, 6d, 79, a3, ec, 69, f7, a8, 07, ad, 56, 7c] reset_token=[a4, 6f, cc, e2, e0, fd, 85, a6, 9e, 63, c8, fb, 6d, ca, a8, 51]
[2025-09-12T06:29:55.330890339Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[dc, 3b, ce, 4b, d8, 12, 38, 59, 9b, 44, f8, 56, b2, 57, fe, 7b, 93, 5e, 02, 42] reset_token=[5a, 3d, 52, 74, a2, 50, 45, 3c, 19, 21, 17, a7, f2, ef, 4f, 1f]
[2025-09-12T06:29:55.330895889Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b0, 63, 21, a7, 6c, 4f, 59, 0d, 00, 67, a4, 48, 56, 3c, 79, f5, 5d, 08, c0, 94] reset_token=[c9, 17, bd, 92, 7c, 60, ef, 55, 15, 01, 7e, b2, cd, 0a, 97, a3]
[2025-09-12T06:29:55.330901309Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[10, 9b, 6e, 39, 44, 14, 2a, 51, c0, 25, 02, a7, 58, 13, b6, 68, ca, a5, c6, 22] reset_token=[30, 38, ef, 41, 18, 8d, b8, d7, 54, b5, 7c, 1c, d8, 1c, 68, 8c]
[2025-09-12T06:29:55.330906519Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[e0, d5, 45, 5e, 70, ea, 58, f0, 5b, a4, 24, 1e, 1c, da, 93, f8, af, f6, cc, a3] reset_token=[fd, d7, 1f, 71, 8c, 77, 30, 9c, ab, a6, b2, 7c, 1b, 44, fc, 61]
[2025-09-12T06:29:55.330911638Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[23, 8a, ae, 9b, 75, bf, 20, d0, 3b, 3e, e2, 9d, 0b, a8, 59, 8a, 49, 52, 47, 4a] reset_token=[17, 7e, 50, b2, 63, 6b, 7c, b9, 6e, 49, 36, 35, 6a, 70, cb, 54]
[2025-09-12T06:29:55.330916838Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[1b, 56, 9d, 99, 91, 47, 3e, 74, ec, 8e, 88, 8c, 13, 6c, 57, 73, 64, 3a, 0d, 5b] reset_token=[24, c1, 7b, 96, 5f, 59, df, 18, c7, 69, c9, 82, d4, 85, bd, f7]
[2025-09-12T06:29:55.330922008Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-09-12T06:29:55.330924542Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm PADDING len=800
[2025-09-12T06:29:55.330947165Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=100.94584ms latest_rtt=33.696961ms srtt=33.696961ms min_rtt=33.696961ms rttvar=16.84848ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1229 app_limited=true congestion_recovery_start_time=None Rate { delivered: 385, delivered_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { bandwidth: 91.40 Kbps, is_app_limited: true, interval: 33.696961ms, delivered: 385, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 86270328 }), send_elapsed: 0ns, ack_elapsed: 33.696961ms, rtt: 33.696961ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1130, rate: 500786, last_update: Instant { tv_sec: 714, tv_nsec: 120530913 }, next_time: Instant { tv_sec: 714, tv_nsec: 120728602 }, max_datagram_size: 1350, last_packet_size: Some(1130), 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:29:55.330982822Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:36811 to 193.167.100.100:443
[2025-09-12T06:29:55.330991127Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Short dcid=11862e0f279de7c0 key_phase=false len=6 pn=5 
[2025-09-12T06:29:55.330993722Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-09-12T06:29:55.330999513Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=100.893603ms latest_rtt=33.696961ms srtt=33.696961ms min_rtt=33.696961ms rttvar=16.84848ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1261 app_limited=true congestion_recovery_start_time=None Rate { delivered: 385, delivered_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { bandwidth: 91.40 Kbps, is_app_limited: true, interval: 33.696961ms, delivered: 385, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 86270328 }), send_elapsed: 0ns, ack_elapsed: 33.696961ms, rtt: 33.696961ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 500786, last_update: Instant { tv_sec: 714, tv_nsec: 120715378 }, next_time: Instant { tv_sec: 714, tv_nsec: 120728602 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.320352ms, 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:29:55.331028717Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:36811 to 193.167.100.100:443
[2025-09-12T06:29:55.331034598Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Short dcid=11862e0f279de7c0 key_phase=false len=6 pn=6 
[2025-09-12T06:29:55.331037083Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-09-12T06:29:55.331041882Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=100.851203ms latest_rtt=33.696961ms srtt=33.696961ms min_rtt=33.696961ms rttvar=16.84848ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1293 app_limited=true congestion_recovery_start_time=None Rate { delivered: 385, delivered_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { bandwidth: 91.40 Kbps, is_app_limited: true, interval: 33.696961ms, delivered: 385, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 86270328 }), send_elapsed: 0ns, ack_elapsed: 33.696961ms, rtt: 33.696961ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 500786, last_update: Instant { tv_sec: 714, tv_nsec: 120715378 }, next_time: Instant { tv_sec: 714, tv_nsec: 123080744 }, 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:29:55.331065165Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:36811 to 193.167.100.100:443
[2025-09-12T06:29:55.331070566Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Short dcid=11862e0f279de7c0 key_phase=false len=72 pn=7 
[2025-09-12T06:29:55.331072990Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm STREAM id=0 off=0 len=67 fin=true
[2025-09-12T06:29:55.331077999Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=100.815116ms latest_rtt=33.696961ms srtt=33.696961ms min_rtt=33.696961ms rttvar=16.84848ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1391 app_limited=true congestion_recovery_start_time=None Rate { delivered: 385, delivered_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { bandwidth: 91.40 Kbps, is_app_limited: true, interval: 33.696961ms, delivered: 385, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 86270328 }), send_elapsed: 0ns, ack_elapsed: 33.696961ms, rtt: 33.696961ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 500786, last_update: Instant { tv_sec: 714, tv_nsec: 120796509 }, next_time: Instant { tv_sec: 714, tv_nsec: 123080744 }, max_datagram_size: 1350, last_packet_size: None, iv: 259.592µ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:29:55.331105210Z TRACE quiche_apps::client] written 98 bytes from 0.0.0.0:36811 to 193.167.100.100:443
[2025-09-12T06:29:55.331108967Z TRACE quiche_apps::client] 0.0.0.0:36811 -> 193.167.100.100:443: done writing
[2025-09-12T06:29:55.363827645Z TRACE quiche_apps::client] got 583 bytes from 193.167.100.100:443 to 0.0.0.0:36811
[2025-09-12T06:29:55.363842763Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx pkt Short dcid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 key_phase=false len=562 pn=2 
[2025-09-12T06:29:55.363852151Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx frm CRYPTO off=0 len=540
[2025-09-12T06:29:55.363877738Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx frm HANDSHAKE_DONE
[2025-09-12T06:29:55.363882598Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 dropped epoch 1 state
[2025-09-12T06:29:55.363892306Z TRACE quiche_apps::client] 0.0.0.0:36811: processed 583 bytes
[2025-09-12T06:29:55.363896954Z TRACE quiche_apps::client] 0.0.0.0:36811: recv() would block
[2025-09-12T06:29:55.363899950Z TRACE quiche_apps::client] done reading
[2025-09-12T06:29:55.363910169Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Short dcid=11862e0f279de7c0 key_phase=false len=5 pn=8 
[2025-09-12T06:29:55.363913676Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm ACK delay=8 blocks=[2..2] ecn_counts=None
[2025-09-12T06:29:55.363922983Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=93.235387ms latest_rtt=33.696961ms srtt=33.696961ms min_rtt=33.696961ms rttvar=16.84848ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1292 app_limited=true congestion_recovery_start_time=None Rate { delivered: 385, delivered_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 120397744 }, end_of_app_limited: 6, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { bandwidth: 91.40 Kbps, is_app_limited: true, interval: 33.696961ms, delivered: 385, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 86270328 }), send_elapsed: 0ns, ack_elapsed: 33.696961ms, rtt: 33.696961ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 500786, last_update: Instant { tv_sec: 714, tv_nsec: 153633368 }, next_time: Instant { tv_sec: 714, tv_nsec: 153892960 }, 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:29:55.363968238Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:36811 to 193.167.100.100:443
[2025-09-12T06:29:55.363973918Z TRACE quiche_apps::client] 0.0.0.0:36811 -> 193.167.100.100:443: done writing
[2025-09-12T06:29:55.363982965Z TRACE quiche_apps::client] got 174 bytes from 193.167.100.100:443 to 0.0.0.0:36811
[2025-09-12T06:29:55.363987714Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 dropped invalid packet
[2025-09-12T06:29:55.363990950Z TRACE quiche_apps::client] 0.0.0.0:36811: processed 174 bytes
[2025-09-12T06:29:55.363995458Z TRACE quiche_apps::client] got 48 bytes from 193.167.100.100:443 to 0.0.0.0:36811
[2025-09-12T06:29:55.363999286Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx pkt Short dcid=58a183f046ec4e9dfab80ffd1e8f610010b14bb5 key_phase=false len=27 pn=5 
[2025-09-12T06:29:55.364013442Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx frm ACK delay=19 blocks=[4..7] ecn_counts=None
[2025-09-12T06:29:55.364017710Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 packet newly acked 4
[2025-09-12T06:29:55.364021307Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 packet newly acked 5
[2025-09-12T06:29:55.364024413Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 packet newly acked 6
[2025-09-12T06:29:55.364027148Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 packet newly acked 7
[2025-09-12T06:29:55.364031526Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 rx frm STREAM id=0 off=0 len=0 fin=true
[2025-09-12T06:29:55.364046624Z TRACE quiche_apps::client] 0.0.0.0:36811: processed 48 bytes
[2025-09-12T06:29:55.364050982Z TRACE quiche_apps::client] 0.0.0.0:36811: recv() would block
[2025-09-12T06:29:55.364053888Z TRACE quiche_apps::client] done reading
[2025-09-12T06:29:55.364056723Z TRACE quiche::h3] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 stream id 0 is readable
[2025-09-12T06:29:55.364063205Z TRACE quiche::h3::stream] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 read 0 bytes on stream 0
[2025-09-12T06:29:55.364066952Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-12T06:29:55.364069898Z INFO  quiche_apps::common] 1/1 response(s) received in 104.238025ms, closing...
[2025-09-12T06:29:55.364078063Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx pkt Short dcid=11862e0f279de7c0 key_phase=false len=11 pn=9 
[2025-09-12T06:29:55.364081269Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T06:29:55.364089013Z TRACE quiche::recovery::congestion::recovery] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 timer=111.897027ms latest_rtt=30.646269ms srtt=33.315623ms min_rtt=30.646269ms rttvar=13.399033ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1677, delivered_time: Instant { tv_sec: 714, tv_nsec: 153892960 }, first_sent_time: Instant { tv_sec: 714, tv_nsec: 153892960 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 7, rate_sample: RateSample { bandwidth: 310.11 Kbps, is_app_limited: true, interval: 33.329269ms, delivered: 1292, prior_delivered: 385, prior_time: Some(Instant { tv_sec: 714, tv_nsec: 120397744 }), send_elapsed: 2.683ms, ack_elapsed: 33.329269ms, rtt: 30.646269ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 506519, last_update: Instant { tv_sec: 714, tv_nsec: 153803185 }, next_time: Instant { tv_sec: 714, tv_nsec: 153892960 }, max_datagram_size: 1350, last_packet_size: None, iv: 73.048µ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:29:55.364124931Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:36811 to 193.167.100.100:443
[2025-09-12T06:29:55.364129199Z TRACE quiche_apps::client] 0.0.0.0:36811 -> 193.167.100.100:443: done writing
[2025-09-12T06:29:55.625443929Z TRACE quiche_apps::client] timed out
[2025-09-12T06:29:55.625458316Z TRACE quiche] 58a183f046ec4e9dfab80ffd1e8f610010b14bb5 draining timeout expired
[2025-09-12T06:29:55.625486809Z TRACE quiche_apps::client] done reading
[2025-09-12T06:29:55.625492129Z INFO  quiche_apps::client] connection closed, recv=4 sent=10 lost=0 retrans=0 sent_bytes=2298 recv_bytes=1883 lost_bytes=0 [local_addr=0.0.0.0:36811 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=4 sent=10 lost=0 retrans=0 rtt=33.315623ms min_rtt=Some(30.646269ms) rttvar=13.399033ms cwnd=13500 sent_bytes=2298 recv_bytes=1883 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=38764]