[2025-09-12T06:35:29.361193127Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T06:35:29.361503887Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:42459 with scid 7d7e8f8733bd6168bb946c3817481d9cbee22666
[2025-09-12T06:35:29.361547819Z TRACE quiche::tls] 7d7e8f8733bd6168bb946c3817481d9cbee22666 write message lvl=Initial len=266
[2025-09-12T06:35:29.361564621Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Initial version=1 dcid=92a4697415c19f84e9b091f63e266bf1 scid=7d7e8f8733bd6168bb946c3817481d9cbee22666 len=270 pn=0 
[2025-09-12T06:35:29.361567676Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm CRYPTO off=0 len=266
[2025-09-12T06:35:29.361582123Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=998.93043ms 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: 1048, tv_nsec: 151241563 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 151241563 }, 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: 1048, tv_nsec: 151241563 }, next_time: Instant { tv_sec: 1048, tv_nsec: 151241563 }, 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:35:29.361632007Z TRACE quiche_apps::client] written 1200
[2025-09-12T06:35:29.400351752Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:42459
[2025-09-12T06:35:29.400366710Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx pkt Initial version=1 dcid=7d7e8f8733bd6168bb946c3817481d9cbee22666 scid=eaacf94fa4bdeb7a token= len=117 pn=0 
[2025-09-12T06:35:29.400377139Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx frm ACK delay=137 blocks=[0..0] ecn_counts=None
[2025-09-12T06:35:29.400382619Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 packet newly acked 0
[2025-09-12T06:35:29.400387799Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx frm CRYPTO off=0 len=90
[2025-09-12T06:35:29.400486834Z TRACE quiche::tls] 7d7e8f8733bd6168bb946c3817481d9cbee22666 set write secret lvl=Handshake
[2025-09-12T06:35:29.400497003Z TRACE quiche::tls] 7d7e8f8733bd6168bb946c3817481d9cbee22666 set read secret lvl=Handshake
[2025-09-12T06:35:29.400523433Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx pkt Handshake version=1 dcid=7d7e8f8733bd6168bb946c3817481d9cbee22666 scid=eaacf94fa4bdeb7a len=1060 pn=1 
[2025-09-12T06:35:29.400529834Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx frm CRYPTO off=0 len=709
[2025-09-12T06:35:29.400741240Z TRACE quiche::tls] 7d7e8f8733bd6168bb946c3817481d9cbee22666 write message lvl=Handshake len=36
[2025-09-12T06:35:29.400745758Z TRACE quiche::tls] 7d7e8f8733bd6168bb946c3817481d9cbee22666 set write secret lvl=OneRTT
[2025-09-12T06:35:29.400753693Z TRACE quiche::tls] 7d7e8f8733bd6168bb946c3817481d9cbee22666 set read secret lvl=OneRTT
[2025-09-12T06:35:29.400771376Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 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(92a4697415c19f84e9b091f63e266bf1), max_idle_timeout: 30000, stateless_reset_token: Some(199485387880438161267462239126618862371), 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(eaacf94fa4bdeb7a), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-09-12T06:35:29.400791483Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx frm PADDING len=330
[2025-09-12T06:35:29.400807794Z TRACE quiche_apps::client] 0.0.0.0:42459: processed 1252 bytes
[2025-09-12T06:35:29.400813374Z TRACE quiche_apps::client] 0.0.0.0:42459: recv() would block
[2025-09-12T06:35:29.400816430Z TRACE quiche_apps::client] done reading
[2025-09-12T06:35:29.400831288Z TRACE quiche::h3] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm SETTINGS stream=2 len=18
[2025-09-12T06:35:29.400849362Z TRACE quiche::h3] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm GREASE stream=0 len=0
[2025-09-12T06:35:29.400856485Z TRACE quiche::h3] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm GREASE stream=0 len=18
[2025-09-12T06:35:29.400864420Z TRACE quiche::h3] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm HEADERS stream=0 len=29 fin=true
[2025-09-12T06:35:29.400871353Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /ekssunwelt", "user-agent: quiche"]
[2025-09-12T06:35:29.400939330Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Initial version=1 dcid=eaacf94fa4bdeb7a scid=7d7e8f8733bd6168bb946c3817481d9cbee22666 len=6 pn=1 
[2025-09-12T06:35:29.400943918Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm ACK delay=71 blocks=[0..0] ecn_counts=None
[2025-09-12T06:35:29.400954107Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=115.961707ms latest_rtt=38.85081ms srtt=38.85081ms min_rtt=38.85081ms rttvar=19.425405ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.57 Kbps, is_app_limited: true, interval: 38.85081ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1048, tv_nsec: 151241563 }), send_elapsed: 0ns, ack_elapsed: 38.85081ms, rtt: 38.85081ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434353, last_update: Instant { tv_sec: 1048, tv_nsec: 190498191 }, next_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, 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:35:29.400975848Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Handshake version=1 dcid=eaacf94fa4bdeb7a scid=7d7e8f8733bd6168bb946c3817481d9cbee22666 len=45 pn=2 
[2025-09-12T06:35:29.400979405Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm ACK delay=56 blocks=[1..1] ecn_counts=None
[2025-09-12T06:35:29.400982661Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm CRYPTO off=0 len=36
[2025-09-12T06:35:29.400989504Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=116.494081ms latest_rtt=38.85081ms srtt=38.85081ms min_rtt=38.85081ms rttvar=19.425405ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.57 Kbps, is_app_limited: true, interval: 38.85081ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1048, tv_nsec: 151241563 }), send_elapsed: 0ns, ack_elapsed: 38.85081ms, rtt: 38.85081ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434353, last_update: Instant { tv_sec: 1048, tv_nsec: 190659953 }, next_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, max_datagram_size: 1350, last_packet_size: None, iv: 227.925µ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:35:29.401008669Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 dropped epoch 0 state
[2025-09-12T06:35:29.401024168Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Short dcid=eaacf94fa4bdeb7a key_phase=false len=1164 pn=3 
[2025-09-12T06:35:29.401027404Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[8a, d0, b4, 13, 30, e9, da, 45, 0a, b4, 9f, 4c, 7a, 10, d7, 47, 6a, f4, db, 5e] reset_token=[39, 41, cd, 77, 89, 4c, f5, 79, db, ab, d8, 8a, b8, ef, 7b, 89]
[2025-09-12T06:35:29.401034918Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[7e, 9d, dd, ad, 83, 1d, b9, a8, f0, 70, 04, cb, 41, 5b, 1a, 23, ad, fa, df, d6] reset_token=[76, 76, d9, e7, ba, 71, ad, 01, 17, d0, 26, fa, 5f, ef, fe, 37]
[2025-09-12T06:35:29.401041340Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b5, b9, 52, be, 2c, cb, 5d, 8c, d5, 16, 73, 93, 01, 39, f5, b6, 24, e3, c2, 1f] reset_token=[25, 91, b3, 5d, 89, 4b, f2, ab, 27, e4, 5d, ac, cd, 23, c0, ad]
[2025-09-12T06:35:29.401047261Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[cc, 4b, 24, 64, 7b, e1, 52, 6e, 8c, 69, 7a, 77, 08, 81, 20, 8f, 1a, 98, fc, db] reset_token=[e1, c5, 6b, e4, a9, b9, 9c, 88, d8, f5, be, 05, b9, d0, ec, 96]
[2025-09-12T06:35:29.401053252Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[5d, 0a, 0d, 32, af, d7, 78, 05, a1, 0a, a3, c8, 24, 93, df, d5, 1a, 8c, 0d, 6e] reset_token=[16, 02, 42, 0a, 8d, 72, 12, 35, d0, 3a, 70, 07, 8d, 55, 79, 82]
[2025-09-12T06:35:29.401059604Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[e2, 2f, 0f, a8, 62, e1, a4, 15, c1, f2, ed, d1, a3, bf, 09, 08, fd, 90, 52, d4] reset_token=[00, 04, c2, a9, 17, 65, a2, c6, 9e, a6, 0a, 8b, 5a, 02, 67, 09]
[2025-09-12T06:35:29.401066026Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[e5, 7f, 27, 6c, df, 52, ea, e1, 09, 7d, 9f, e2, 14, e8, 94, ac, 71, b2, f2, 00] reset_token=[82, c8, b3, ef, d1, 3d, 42, d0, 75, 65, e4, 82, c4, 13, ae, 36]
[2025-09-12T06:35:29.401072098Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-09-12T06:35:29.401075294Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm PADDING len=860
[2025-09-12T06:35:29.401104528Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=116.378946ms latest_rtt=38.85081ms srtt=38.85081ms min_rtt=38.85081ms rttvar=19.425405ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.57 Kbps, is_app_limited: true, interval: 38.85081ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1048, tv_nsec: 151241563 }), send_elapsed: 0ns, ack_elapsed: 38.85081ms, rtt: 38.85081ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1190, rate: 434353, last_update: Instant { tv_sec: 1048, tv_nsec: 190659953 }, next_time: Instant { tv_sec: 1048, tv_nsec: 190887878 }, 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:35:29.401149112Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:42459 to 193.167.100.100:443
[2025-09-12T06:35:29.401158840Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Short dcid=eaacf94fa4bdeb7a key_phase=false len=6 pn=4 
[2025-09-12T06:35:29.401162026Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-09-12T06:35:29.401169670Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=116.313924ms latest_rtt=38.85081ms srtt=38.85081ms min_rtt=38.85081ms rttvar=19.425405ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.57 Kbps, is_app_limited: true, interval: 38.85081ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1048, tv_nsec: 151241563 }), send_elapsed: 0ns, ack_elapsed: 38.85081ms, rtt: 38.85081ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434353, last_update: Instant { tv_sec: 1048, tv_nsec: 190882469 }, next_time: Instant { tv_sec: 1048, tv_nsec: 190887878 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.81338ms, 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:35:29.401196300Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:42459 to 193.167.100.100:443
[2025-09-12T06:35:29.401201760Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Short dcid=eaacf94fa4bdeb7a key_phase=false len=6 pn=5 
[2025-09-12T06:35:29.401204125Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-09-12T06:35:29.401208833Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=116.274831ms latest_rtt=38.85081ms srtt=38.85081ms min_rtt=38.85081ms rttvar=19.425405ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.57 Kbps, is_app_limited: true, interval: 38.85081ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1048, tv_nsec: 151241563 }), send_elapsed: 0ns, ack_elapsed: 38.85081ms, rtt: 38.85081ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 434353, last_update: Instant { tv_sec: 1048, tv_nsec: 190882469 }, next_time: Instant { tv_sec: 1048, tv_nsec: 193741274 }, 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:35:29.401235343Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:42459 to 193.167.100.100:443
[2025-09-12T06:35:29.401240783Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Short dcid=eaacf94fa4bdeb7a key_phase=false len=72 pn=6 
[2025-09-12T06:35:29.401243227Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm STREAM id=0 off=0 len=67 fin=true
[2025-09-12T06:35:29.401248277Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=116.235417ms latest_rtt=38.85081ms srtt=38.85081ms min_rtt=38.85081ms rttvar=19.425405ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1451 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.57 Kbps, is_app_limited: true, interval: 38.85081ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1048, tv_nsec: 151241563 }), send_elapsed: 0ns, ack_elapsed: 38.85081ms, rtt: 38.85081ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434353, last_update: Instant { tv_sec: 1048, tv_nsec: 190966867 }, next_time: Instant { tv_sec: 1048, tv_nsec: 193741274 }, max_datagram_size: 1350, last_packet_size: None, iv: 299.296µ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:35:29.401269597Z TRACE quiche_apps::client] written 98 bytes from 0.0.0.0:42459 to 193.167.100.100:443
[2025-09-12T06:35:29.401288061Z TRACE quiche_apps::client] 0.0.0.0:42459 -> 193.167.100.100:443: done writing
[2025-09-12T06:35:29.434261678Z TRACE quiche_apps::client] got 583 bytes from 193.167.100.100:443 to 0.0.0.0:42459
[2025-09-12T06:35:29.434301863Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx pkt Short dcid=7d7e8f8733bd6168bb946c3817481d9cbee22666 key_phase=false len=562 pn=2 
[2025-09-12T06:35:29.434312343Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx frm CRYPTO off=0 len=540
[2025-09-12T06:35:29.434340515Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx frm HANDSHAKE_DONE
[2025-09-12T06:35:29.434346035Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 dropped epoch 1 state
[2025-09-12T06:35:29.434356675Z TRACE quiche_apps::client] 0.0.0.0:42459: processed 583 bytes
[2025-09-12T06:35:29.434362145Z TRACE quiche_apps::client] 0.0.0.0:42459: recv() would block
[2025-09-12T06:35:29.434365362Z TRACE quiche_apps::client] done reading
[2025-09-12T06:35:29.434376603Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Short dcid=eaacf94fa4bdeb7a key_phase=false len=5 pn=7 
[2025-09-12T06:35:29.434380339Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm ACK delay=9 blocks=[2..2] ecn_counts=None
[2025-09-12T06:35:29.434390629Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=108.399579ms latest_rtt=38.85081ms srtt=38.85081ms min_rtt=38.85081ms rttvar=19.425405ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1352 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 190498231 }, end_of_app_limited: 5, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 68.57 Kbps, is_app_limited: true, interval: 38.85081ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1048, tv_nsec: 151241563 }), send_elapsed: 0ns, ack_elapsed: 38.85081ms, rtt: 38.85081ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 434353, last_update: Instant { tv_sec: 1048, tv_nsec: 224099270 }, next_time: Instant { tv_sec: 1048, tv_nsec: 224398566 }, 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:35:29.434447315Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:42459 to 193.167.100.100:443
[2025-09-12T06:35:29.434453797Z TRACE quiche_apps::client] 0.0.0.0:42459 -> 193.167.100.100:443: done writing
[2025-09-12T06:35:29.434463766Z TRACE quiche_apps::client] got 314 bytes from 193.167.100.100:443 to 0.0.0.0:42459
[2025-09-12T06:35:29.434468384Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 dropped invalid packet
[2025-09-12T06:35:29.434471830Z TRACE quiche_apps::client] 0.0.0.0:42459: processed 314 bytes
[2025-09-12T06:35:29.434476810Z TRACE quiche_apps::client] got 48 bytes from 193.167.100.100:443 to 0.0.0.0:42459
[2025-09-12T06:35:29.434480857Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx pkt Short dcid=7d7e8f8733bd6168bb946c3817481d9cbee22666 key_phase=false len=27 pn=5 
[2025-09-12T06:35:29.434487350Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx frm ACK delay=16 blocks=[3..6] ecn_counts=None
[2025-09-12T06:35:29.434491728Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 packet newly acked 3
[2025-09-12T06:35:29.434495635Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 packet newly acked 4
[2025-09-12T06:35:29.434499041Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 packet newly acked 5
[2025-09-12T06:35:29.434501947Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 packet newly acked 6
[2025-09-12T06:35:29.434506686Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 rx frm STREAM id=0 off=0 len=0 fin=true
[2025-09-12T06:35:29.434523267Z TRACE quiche_apps::client] 0.0.0.0:42459: processed 48 bytes
[2025-09-12T06:35:29.434527795Z TRACE quiche_apps::client] 0.0.0.0:42459: recv() would block
[2025-09-12T06:35:29.434530921Z TRACE quiche_apps::client] done reading
[2025-09-12T06:35:29.434534157Z TRACE quiche::h3] 7d7e8f8733bd6168bb946c3817481d9cbee22666 stream id 0 is readable
[2025-09-12T06:35:29.434540940Z TRACE quiche::h3::stream] 7d7e8f8733bd6168bb946c3817481d9cbee22666 read 0 bytes on stream 0
[2025-09-12T06:35:29.434544937Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-12T06:35:29.434548013Z INFO  quiche_apps::common] 1/1 response(s) received in 72.91247ms, closing...
[2025-09-12T06:35:29.434556909Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx pkt Short dcid=eaacf94fa4bdeb7a key_phase=false len=11 pn=8 
[2025-09-12T06:35:29.434560366Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T06:35:29.434568852Z TRACE quiche::recovery::congestion::recovery] 7d7e8f8733bd6168bb946c3817481d9cbee22666 timer=129.446628ms latest_rtt=30.4672ms srtt=37.802858ms min_rtt=30.4672ms rttvar=16.664955ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1685, delivered_time: Instant { tv_sec: 1048, tv_nsec: 224398566 }, first_sent_time: Instant { tv_sec: 1048, tv_nsec: 224398566 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 6, rate_sample: RateSample { bandwidth: 320.85 Kbps, is_app_limited: true, interval: 33.710243ms, delivered: 1352, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1048, tv_nsec: 190498231 }), send_elapsed: 3.243043ms, ack_elapsed: 33.710243ms, rtt: 30.4672ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 446394, last_update: Instant { tv_sec: 1048, tv_nsec: 224281711 }, next_time: Instant { tv_sec: 1048, tv_nsec: 224398566 }, max_datagram_size: 1350, last_packet_size: None, iv: 82.886µ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:35:29.434613455Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:42459 to 193.167.100.100:443
[2025-09-12T06:35:29.434618545Z TRACE quiche_apps::client] 0.0.0.0:42459 -> 193.167.100.100:443: done writing
[2025-09-12T06:35:29.748956376Z TRACE quiche_apps::client] timed out
[2025-09-12T06:35:29.748967567Z TRACE quiche] 7d7e8f8733bd6168bb946c3817481d9cbee22666 draining timeout expired
[2025-09-12T06:35:29.748992714Z TRACE quiche_apps::client] done reading
[2025-09-12T06:35:29.748997513Z INFO  quiche_apps::client] connection closed, recv=4 sent=9 lost=0 retrans=0 sent_bytes=1913 recv_bytes=1883 lost_bytes=0 [local_addr=0.0.0.0:42459 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=4 sent=9 lost=0 retrans=0 rtt=37.802858ms min_rtt=Some(30.4672ms) rttvar=16.664955ms cwnd=13500 sent_bytes=1913 recv_bytes=1883 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=40106]