[2025-09-12T02:10:45.074758290Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-09-12T02:10:45.075040099Z INFO quiche_apps::client] connecting to [fd00:cafe:cafe:100::100]:443 from [::]:47208 with scid 804c93ea5f256e9ea123c1e3b78e24e04260464b [2025-09-12T02:10:45.075083309Z TRACE quiche::tls] 804c93ea5f256e9ea123c1e3b78e24e04260464b write message lvl=Initial len=266 [2025-09-12T02:10:45.075099730Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Initial version=1 dcid=431329c7a2be48bbb13ab9b701b583b6 scid=804c93ea5f256e9ea123c1e3b78e24e04260464b len=270 pn=0 [2025-09-12T02:10:45.075102966Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm CRYPTO off=0 len=266 [2025-09-12T02:10:45.075117353Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=998.926543ms 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: 1203, tv_nsec: 602921144 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 602921144 }, 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: 1203, tv_nsec: 602921144 }, next_time: Instant { tv_sec: 1203, tv_nsec: 602921144 }, 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-12T02:10:45.075196021Z TRACE quiche_apps::client] written 1200 [2025-09-12T02:10:45.115984938Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.116004114Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Initial version=1 dcid=804c93ea5f256e9ea123c1e3b78e24e04260464b scid=d39d509f token= len=308 pn=0 [2025-09-12T02:10:45.116015836Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm ACK delay=0 blocks=[0..0] ecn_counts=None [2025-09-12T02:10:45.116022058Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b packet newly acked 0 [2025-09-12T02:10:45.116028660Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm PADDING len=191 [2025-09-12T02:10:45.116032407Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm CRYPTO off=0 len=90 [2025-09-12T02:10:45.116170517Z TRACE quiche::tls] 804c93ea5f256e9ea123c1e3b78e24e04260464b set write secret lvl=Handshake [2025-09-12T02:10:45.116183952Z TRACE quiche::tls] 804c93ea5f256e9ea123c1e3b78e24e04260464b set read secret lvl=Handshake [2025-09-12T02:10:45.116215441Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Handshake version=1 dcid=804c93ea5f256e9ea123c1e3b78e24e04260464b scid=d39d509f len=746 pn=0 [2025-09-12T02:10:45.116223205Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm CRYPTO off=0 len=724 [2025-09-12T02:10:45.116453737Z TRACE quiche::tls] 804c93ea5f256e9ea123c1e3b78e24e04260464b write message lvl=Handshake len=36 [2025-09-12T02:10:45.116458827Z TRACE quiche::tls] 804c93ea5f256e9ea123c1e3b78e24e04260464b set write secret lvl=OneRTT [2025-09-12T02:10:45.116467874Z TRACE quiche::tls] 804c93ea5f256e9ea123c1e3b78e24e04260464b set read secret lvl=OneRTT [2025-09-12T02:10:45.116486719Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(431329c7a2be48bbb13ab9b701b583b6), max_idle_timeout: 30000, stateless_reset_token: Some(333299559924373618059447242116211341654), max_udp_payload_size: 1452, initial_max_data: 786432, initial_max_stream_data_bidi_local: 524288, initial_max_stream_data_bidi_remote: 524288, initial_max_stream_data_uni: 524288, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 26, disable_active_migration: false, active_conn_id_limit: 4, initial_source_connection_id: Some(d39d509f), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-09-12T02:10:45.116530331Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=804c93ea5f256e9ea123c1e3b78e24e04260464b key_phase=false len=138 pn=0 [2025-09-12T02:10:45.116538506Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[78, 8d, 21, 26] reset_token=[e6, 5d, a2, 7c, 6e, b0, 48, 50, ab, 64, bf, 9e, a0, d5, 06, e7] [2025-09-12T02:10:45.116546461Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[f0, 00, cf, 8c] reset_token=[fb, d8, 01, 28, b0, 5f, 70, be, 57, 39, d7, b1, da, 66, af, d1] [2025-09-12T02:10:45.116552242Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[8b, ef, 22, ec] reset_token=[f0, 46, 05, d7, 14, 51, 5f, 74, 8c, 4e, ee, 1e, 10, a6, 9a, 24] [2025-09-12T02:10:45.116557542Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[43, 1e, 92, 9a] reset_token=[5b, a1, 82, 4e, 4a, 86, 4e, 17, bc, d1, ea, 99, 43, 94, f0, 4b] [2025-09-12T02:10:45.116563503Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[8b, c2, 7e, c3] reset_token=[85, d8, f4, 1a, ed, 43, 64, ed, 94, ad, 9e, 7f, ba, 4a, 32, 36] [2025-09-12T02:10:45.116572891Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.116579112Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.116582308Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.116591526Z DEBUG quiche_apps::common] sending HTTP request "GET /gilvcmbkwm\r\n" [2025-09-12T02:10:45.116639656Z DEBUG quiche_apps::common] sending HTTP request "GET /vcxfzfpipf\r\n" [2025-09-12T02:10:45.116684901Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Initial version=1 dcid=d39d509f scid=804c93ea5f256e9ea123c1e3b78e24e04260464b len=6 pn=1 [2025-09-12T02:10:45.116689630Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=85 blocks=[0..0] ecn_counts=None [2025-09-12T02:10:45.116701041Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=122.159495ms latest_rtt=40.953436ms srtt=40.953436ms min_rtt=40.953436ms rttvar=20.476718ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 65.05 Kbps, is_app_limited: true, interval: 40.953436ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 602921144 }), send_elapsed: 0ns, ack_elapsed: 40.953436ms, rtt: 40.953436ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 412053, last_update: Instant { tv_sec: 1203, tv_nsec: 644358687 }, next_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, 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-12T02:10:45.116724836Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Handshake version=1 dcid=d39d509f scid=804c93ea5f256e9ea123c1e3b78e24e04260464b len=45 pn=2 [2025-09-12T02:10:45.116734463Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=63 blocks=[0..0] ecn_counts=None [2025-09-12T02:10:45.116738181Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm CRYPTO off=0 len=36 [2025-09-12T02:10:45.116745825Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=122.790708ms latest_rtt=40.953436ms srtt=40.953436ms min_rtt=40.953436ms rttvar=20.476718ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=95 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 65.05 Kbps, is_app_limited: true, interval: 40.953436ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 602921144 }), send_elapsed: 0ns, ack_elapsed: 40.953436ms, rtt: 40.953436ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 412053, last_update: Instant { tv_sec: 1203, tv_nsec: 644550377 }, next_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, max_datagram_size: 1350, last_packet_size: None, iv: 230.553µ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-12T02:10:45.116760071Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b dropped epoch 0 state [2025-09-12T02:10:45.116777183Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=1176 pn=3 [2025-09-12T02:10:45.116780931Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=29 blocks=[0..0] ecn_counts=None [2025-09-12T02:10:45.116784327Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[5a, 0a, 6f, c4, fb, 87, 7f, 72, ce, 26, a6, 2b, 34, f4, c8, 8b, 39, b1, a8, fc] reset_token=[dd, 63, 26, 9b, 0f, 83, 08, 82, 3d, 1c, 21, 1e, e0, 45, fb, 2c] [2025-09-12T02:10:45.116792031Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[12, f0, d5, 62, de, bc, c6, fb, d6, c7, 98, 84, e0, d7, 66, 00, d8, 87, dd, 61] reset_token=[67, db, 41, b6, 99, 33, b3, c8, 3d, b6, cf, e9, 5c, 05, 74, 28] [2025-09-12T02:10:45.116798684Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[55, ac, f2, c7, 13, 59, 12, 5c, e6, 01, 98, a2, ba, 74, 05, 2d, 85, 51, ae, 3d] reset_token=[38, 40, 30, 3c, bf, 90, e9, 19, 00, 54, 93, 3d, f6, 2f, a0, 2c] [2025-09-12T02:10:45.116805306Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm STREAM id=0 off=0 len=17 fin=true [2025-09-12T02:10:45.116808733Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm PADDING len=1029 [2025-09-12T02:10:45.116818311Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=122.718242ms latest_rtt=40.953436ms srtt=40.953436ms min_rtt=40.953436ms rttvar=20.476718ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1293 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 65.05 Kbps, is_app_limited: true, interval: 40.953436ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 602921144 }), send_elapsed: 0ns, ack_elapsed: 40.953436ms, rtt: 40.953436ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1198, rate: 412053, last_update: Instant { tv_sec: 1203, tv_nsec: 644550377 }, next_time: Instant { tv_sec: 1203, tv_nsec: 644780930 }, max_datagram_size: 1350, last_packet_size: Some(1198), 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-12T02:10:45.116866681Z TRACE quiche_apps::client] written 1350 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.116882090Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=22 pn=4 [2025-09-12T02:10:45.116887570Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm STREAM id=4 off=0 len=17 fin=true [2025-09-12T02:10:45.116899984Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=122.635948ms latest_rtt=40.953436ms srtt=40.953436ms min_rtt=40.953436ms rttvar=20.476718ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1337 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 644358727 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 65.05 Kbps, is_app_limited: true, interval: 40.953436ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 602921144 }), send_elapsed: 0ns, ack_elapsed: 40.953436ms, rtt: 40.953436ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 412053, last_update: Instant { tv_sec: 1203, tv_nsec: 644749199 }, next_time: Instant { tv_sec: 1203, tv_nsec: 644780930 }, max_datagram_size: 1350, last_packet_size: None, iv: 3.014175ms, 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-12T02:10:45.116946030Z TRACE quiche_apps::client] written 44 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.116955157Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.149152479Z TRACE quiche_apps::client] got 315 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.149167137Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=294 pn=1 [2025-09-12T02:10:45.149175222Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b path ID 0 now see SCID with seq num 1 [2025-09-12T02:10:45.149180682Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm ACK delay=44 blocks=[3..4] ecn_counts=None [2025-09-12T02:10:45.149185501Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b packet newly acked 3 [2025-09-12T02:10:45.149188687Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b packet newly acked 4 [2025-09-12T02:10:45.149193757Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm RETIRE_CONNECTION_ID seq_num=0 [2025-09-12T02:10:45.149201391Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm NEW_TOKEN len=91 [2025-09-12T02:10:45.149204256Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm CRYPTO off=0 len=170 [2025-09-12T02:10:45.149221979Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm HANDSHAKE_DONE [2025-09-12T02:10:45.149227019Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b dropped epoch 1 state [2025-09-12T02:10:45.149243089Z TRACE quiche_apps::client] [::]:47208: processed 315 bytes [2025-09-12T02:10:45.149247197Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.149255963Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.149259149Z INFO quiche_apps::client] Retiring source CID 804c93ea5f256e9ea123c1e3b78e24e04260464b [2025-09-12T02:10:45.149272634Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=45 pn=5 [2025-09-12T02:10:45.149276001Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=13 blocks=[1..1] ecn_counts=None [2025-09-12T02:10:45.149279006Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=1 conn_id=[e7, 5e, 3f, 5f, f3, c8, bf, ac, 88, f3, ad, fb, b5, 5f, 40, 2e, d7, df, 8f, d6] reset_token=[ff, d8, db, d4, cc, 6e, 09, 54, 67, 98, 48, 8d, f5, c0, 35, fb] [2025-09-12T02:10:45.149307460Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=135.951067ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 67, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 677140064 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, max_datagram_size: 1350, last_packet_size: Some(67), 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-12T02:10:45.149345882Z TRACE quiche_apps::client] written 67 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.149353626Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.150351546Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.150362937Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=2 [2025-09-12T02:10:45.150370531Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=0 len=1239 fin=false [2025-09-12T02:10:45.150378226Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.150381742Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.150384027Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.150388495Z TRACE quiche_apps::common] received 1239 bytes [2025-09-12T02:10:45.150391030Z TRACE quiche_apps::common] stream 4 has 1239 bytes (fin? false) [2025-09-12T02:10:45.150398814Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=6 [2025-09-12T02:10:45.150401499Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..2] ecn_counts=None [2025-09-12T02:10:45.150408102Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=134.850565ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 6, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, max_datagram_size: 1350, last_packet_size: None, iv: 158.285µ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-12T02:10:45.150446203Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.150453797Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.151418185Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.151429075Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=3 [2025-09-12T02:10:45.151436028Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=1239 len=1237 fin=false [2025-09-12T02:10:45.151443593Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.151447129Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.151449414Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.151453692Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.151455976Z TRACE quiche_apps::common] stream 4 has 1237 bytes (fin? false) [2025-09-12T02:10:45.151463440Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=7 [2025-09-12T02:10:45.151466075Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..3] ecn_counts=None [2025-09-12T02:10:45.151472557Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=133.78609ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 7, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.151503315Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.151510278Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.152471561Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.152482571Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=4 [2025-09-12T02:10:45.152489294Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=2476 len=1237 fin=false [2025-09-12T02:10:45.152501928Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.152505494Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.152507738Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.152511917Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.152514141Z TRACE quiche_apps::common] stream 4 has 1237 bytes (fin? false) [2025-09-12T02:10:45.152521604Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=8 [2025-09-12T02:10:45.152524169Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..4] ecn_counts=None [2025-09-12T02:10:45.152530371Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=132.728276ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 8, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.152560928Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.152567931Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.153545664Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.153556404Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=5 [2025-09-12T02:10:45.153562976Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=3713 len=1237 fin=false [2025-09-12T02:10:45.153570300Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.153573717Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.153575961Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.153579908Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.153582132Z TRACE quiche_apps::common] stream 4 has 1237 bytes (fin? false) [2025-09-12T02:10:45.153589316Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=9 [2025-09-12T02:10:45.153591901Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..5] ecn_counts=None [2025-09-12T02:10:45.153598182Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=131.660484ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 9, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.153634861Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.153642456Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.154600370Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.154611340Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=6 [2025-09-12T02:10:45.154618203Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=0 off=0 len=1239 fin=false [2025-09-12T02:10:45.154625747Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.154629194Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.154631438Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.154635546Z TRACE quiche_apps::common] received 1239 bytes [2025-09-12T02:10:45.154637800Z TRACE quiche_apps::common] stream 0 has 1239 bytes (fin? false) [2025-09-12T02:10:45.154645104Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=10 [2025-09-12T02:10:45.154647668Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..6] ecn_counts=None [2025-09-12T02:10:45.154653930Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=130.604777ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 10, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.154683746Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.154691080Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.155673331Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.155684322Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=7 [2025-09-12T02:10:45.155690994Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=4950 len=1237 fin=false [2025-09-12T02:10:45.155703508Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.155707094Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.155709329Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.155713426Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.155715650Z TRACE quiche_apps::common] stream 4 has 1237 bytes (fin? false) [2025-09-12T02:10:45.155722984Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=11 [2025-09-12T02:10:45.155725479Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..7] ecn_counts=None [2025-09-12T02:10:45.155731781Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=129.526906ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 11, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.155762929Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.155770143Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.156727719Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.156738749Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=8 [2025-09-12T02:10:45.156745782Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=0 off=1239 len=1237 fin=false [2025-09-12T02:10:45.156753096Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.156756553Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.156758827Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.156762825Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.156765029Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false) [2025-09-12T02:10:45.156772403Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=12 [2025-09-12T02:10:45.156775097Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..8] ecn_counts=None [2025-09-12T02:10:45.156781229Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=128.477448ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 12, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.156816846Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.156824270Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.157800780Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.157811410Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=9 [2025-09-12T02:10:45.157818012Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=6187 len=1237 fin=false [2025-09-12T02:10:45.157825276Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.157828672Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.157830967Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.157834924Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.157837168Z TRACE quiche_apps::common] stream 4 has 1237 bytes (fin? false) [2025-09-12T02:10:45.157844482Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=13 [2025-09-12T02:10:45.157847007Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..9] ecn_counts=None [2025-09-12T02:10:45.157853719Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=127.404978ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 13, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.157884016Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.157890959Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.158855816Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.158866647Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=10 [2025-09-12T02:10:45.158873380Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=0 off=2476 len=1237 fin=false [2025-09-12T02:10:45.158891954Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.158895581Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.158897835Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.158901943Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.158904167Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false) [2025-09-12T02:10:45.158911561Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=14 [2025-09-12T02:10:45.158914106Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=5 blocks=[1..10] ecn_counts=None [2025-09-12T02:10:45.158920498Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=126.338219ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 14, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.158950694Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.158957928Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.159927305Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.159937985Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=11 [2025-09-12T02:10:45.159944697Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=7424 len=1237 fin=false [2025-09-12T02:10:45.159951911Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.159955308Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.159957562Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.159961459Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.159963663Z TRACE quiche_apps::common] stream 4 has 1237 bytes (fin? false) [2025-09-12T02:10:45.159987518Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=15 [2025-09-12T02:10:45.159990514Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=6 blocks=[1..11] ecn_counts=None [2025-09-12T02:10:45.159996845Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=125.261852ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 15, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.160030529Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.160037842Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.160982865Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.160993695Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=12 [2025-09-12T02:10:45.161000418Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=0 off=3713 len=1237 fin=false [2025-09-12T02:10:45.161007701Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.161011138Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.161013392Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.161017440Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.161019614Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false) [2025-09-12T02:10:45.161026987Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=16 [2025-09-12T02:10:45.161029532Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..12] ecn_counts=None [2025-09-12T02:10:45.161035644Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=124.223054ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 16, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.161065530Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.161072433Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.162049814Z TRACE quiche_apps::client] got 1280 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.162060463Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=1259 pn=13 [2025-09-12T02:10:45.162067246Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=8661 len=1237 fin=false [2025-09-12T02:10:45.162079690Z TRACE quiche_apps::client] [::]:47208: processed 1280 bytes [2025-09-12T02:10:45.162083226Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.162085460Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.162089438Z TRACE quiche_apps::common] received 1237 bytes [2025-09-12T02:10:45.162091642Z TRACE quiche_apps::common] stream 4 has 1237 bytes (fin? false) [2025-09-12T02:10:45.162099216Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=5 pn=17 [2025-09-12T02:10:45.162101761Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm ACK delay=4 blocks=[1..13] ecn_counts=None [2025-09-12T02:10:45.162108023Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=123.150674ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 4, last_sent_packet: 17, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 678268448 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, 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-12T02:10:45.162156453Z TRACE quiche_apps::client] written 27 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.162167093Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.162568556Z TRACE quiche_apps::client] got 561 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.162573545Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=540 pn=14 [2025-09-12T02:10:45.162579075Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=0 off=4950 len=170 fin=true [2025-09-12T02:10:45.162582482Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=9898 len=342 fin=false [2025-09-12T02:10:45.162589114Z TRACE quiche_apps::client] [::]:47208: processed 561 bytes [2025-09-12T02:10:45.162593292Z TRACE quiche_apps::client] got 43 bytes from [fd00:cafe:cafe:100::100]:443 to [::]:47208 [2025-09-12T02:10:45.162596248Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx pkt Short dcid=5a0a6fc4fb877f72ce26a62b34f4c88b39b1a8fc key_phase=false len=22 pn=15 [2025-09-12T02:10:45.162599874Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b rx frm STREAM id=4 off=10240 len=0 fin=true [2025-09-12T02:10:45.162604113Z TRACE quiche_apps::client] [::]:47208: processed 43 bytes [2025-09-12T02:10:45.162607258Z TRACE quiche_apps::client] [::]:47208: recv() would block [2025-09-12T02:10:45.162609512Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.162630712Z TRACE quiche_apps::common] received 170 bytes [2025-09-12T02:10:45.162636553Z TRACE quiche_apps::common] stream 0 has 170 bytes (fin? true) [2025-09-12T02:10:45.162641182Z DEBUG quiche_apps::common] 1/2 responses received [2025-09-12T02:10:45.162644709Z TRACE quiche_apps::common] received 342 bytes [2025-09-12T02:10:45.162646903Z TRACE quiche_apps::common] stream 4 has 342 bytes (fin? true) [2025-09-12T02:10:45.162651531Z DEBUG quiche_apps::common] 2/2 responses received [2025-09-12T02:10:45.162653886Z INFO quiche_apps::common] 2/2 response(s) received in 87.452415ms, closing... [2025-09-12T02:10:45.162660839Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx pkt Short dcid=d39d509f key_phase=false len=10 pn=18 [2025-09-12T02:10:45.162663344Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-09-12T02:10:45.162670136Z TRACE quiche::recovery::congestion::recovery] 804c93ea5f256e9ea123c1e3b78e24e04260464b timer=135.980152ms latest_rtt=32.257323ms srtt=39.866421ms min_rtt=32.257323ms rttvar=17.531566ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1575, delivered_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, first_sent_time: Instant { tv_sec: 1203, tv_nsec: 680154239 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 4, rate_sample: RateSample { bandwidth: 304.04 Kbps, is_app_limited: true, interval: 32.679526ms, delivered: 1242, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 1203, tv_nsec: 644358727 }), send_elapsed: 422.203µs, ack_elapsed: 32.679526ms, rtt: 32.257323ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423288, last_update: Instant { tv_sec: 1203, tv_nsec: 690531645 }, next_time: Instant { tv_sec: 1203, tv_nsec: 680312524 }, max_datagram_size: 1350, last_packet_size: None, iv: 75.599µ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-12T02:10:45.162699712Z TRACE quiche_apps::client] written 32 bytes from [::]:47208 to [fd00:cafe:cafe:100::100]:443 [2025-09-12T02:10:45.162706084Z TRACE quiche_apps::client] [::]:47208 -> [fd00:cafe:cafe:100::100]:443: done writing [2025-09-12T02:10:45.493091186Z TRACE quiche_apps::client] timed out [2025-09-12T02:10:45.493108529Z TRACE quiche] 804c93ea5f256e9ea123c1e3b78e24e04260464b draining timeout expired [2025-09-12T02:10:45.493171697Z TRACE quiche_apps::client] done reading [2025-09-12T02:10:45.493178790Z INFO quiche_apps::client] connection closed, recv=18 sent=19 lost=0 retrans=0 sent_bytes=2150 recv_bytes=17559 lost_bytes=0 [local_addr=[::]:47208 peer_addr=[fd00:cafe:cafe:100::100]:443 validation_state=Validated active=true recv=18 sent=19 lost=0 retrans=0 rtt=39.866421ms min_rtt=Some(32.257323ms) rttvar=17.531566ms cwnd=13500 sent_bytes=2150 recv_bytes=17559 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=38005]