[2025-09-12T05:03:28.126972384Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-12T05:03:28.127250433Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:34387 with scid 76fc363e0aecdc3a57cf7a974bc698bd9e57f652
[2025-09-12T05:03:28.127296279Z TRACE quiche::tls] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 write message lvl=Initial len=345
[2025-09-12T05:03:28.127311418Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Initial version=1 dcid=79527aac1e0ab61f0bffa7ae1ae4dc7e scid=76fc363e0aecdc3a57cf7a974bc698bd9e57f652 len=349 pn=0 
[2025-09-12T05:03:28.127314864Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm CRYPTO off=0 len=345
[2025-09-12T05:03:28.127330483Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=998.926222ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=412 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 528, tv_nsec: 125325217 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 125325217 }, 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: 528, tv_nsec: 125325217 }, next_time: Instant { tv_sec: 528, tv_nsec: 125325217 }, 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-12T05:03:28.127397969Z TRACE quiche_apps::client] written 1200
[2025-09-12T05:03:28.160042226Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.160058356Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Initial version=1 dcid=76fc363e0aecdc3a57cf7a974bc698bd9e57f652 scid=3d2a85b239521c78 token= len=122 pn=0 
[2025-09-12T05:03:28.160068485Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm ACK delay=33 blocks=[0..0] ecn_counts=None
[2025-09-12T05:03:28.160074226Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 packet newly acked 0
[2025-09-12T05:03:28.160079606Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm CRYPTO off=0 len=96
[2025-09-12T05:03:28.160168352Z TRACE quiche::tls] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 set write secret lvl=Handshake
[2025-09-12T05:03:28.160177649Z TRACE quiche::tls] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 set read secret lvl=Handshake
[2025-09-12T05:03:28.160198889Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Handshake version=1 dcid=76fc363e0aecdc3a57cf7a974bc698bd9e57f652 scid=3d2a85b239521c78 len=1003 pn=0 
[2025-09-12T05:03:28.160204840Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm CRYPTO off=0 len=181
[2025-09-12T05:03:28.160234937Z TRACE quiche::tls] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 write message lvl=Handshake len=36
[2025-09-12T05:03:28.160238343Z TRACE quiche::tls] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 set write secret lvl=OneRTT
[2025-09-12T05:03:28.160245486Z TRACE quiche::tls] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 set read secret lvl=OneRTT
[2025-09-12T05:03:28.160261346Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=true TransportParams { original_destination_connection_id: Some(79527aac1e0ab61f0bffa7ae1ae4dc7e), max_idle_timeout: 30000, stateless_reset_token: Some(153675657149540093525118356847224409959), max_udp_payload_size: 1472, initial_max_data: 4611686018427387903, initial_max_stream_data_bidi_local: 1250000, initial_max_stream_data_bidi_remote: 1250000, initial_max_stream_data_uni: 1250000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 5, initial_source_connection_id: Some(3d2a85b239521c78), retry_source_connection_id: None, max_datagram_frame_size: Some(65535), unknown_params: None }
[2025-09-12T05:03:28.160283998Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm PADDING len=801
[2025-09-12T05:03:28.160297804Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 1200 bytes
[2025-09-12T05:03:28.160304156Z TRACE quiche_apps::client] got 234 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.160308223Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=76fc363e0aecdc3a57cf7a974bc698bd9e57f652 key_phase=false len=213 pn=0 
[2025-09-12T05:03:28.160314626Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[4f, b8, 03, f7, db, 50, 50, da] reset_token=[c2, bf, 63, f2, e2, 40, 17, 46, b2, 81, 3c, a8, 70, 1a, 5b, 6b]
[2025-09-12T05:03:28.160322360Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[20, de, b0, bc, fb, d7, 80, c4] reset_token=[d5, 92, 03, 00, 1f, 48, d8, 78, 0f, aa, f7, 03, 10, ee, c6, 7f]
[2025-09-12T05:03:28.160328121Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[40, 27, d4, f6, c7, c9, 2a, 05] reset_token=[44, b3, 8d, 22, 28, 86, 09, 26, 9d, 07, cd, 12, e3, c8, 51, d2]
[2025-09-12T05:03:28.160333671Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[96, 3d, 06, 3f, 2e, d0, 4f, c9] reset_token=[0b, 67, 91, a5, 08, d6, b1, 3e, 7c, 27, 44, 99, 66, 91, 9e, 86]
[2025-09-12T05:03:28.160339652Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[b1, a5, 93, 43, e8, 37, 6e, 21] reset_token=[f5, 9a, d8, 1d, b6, c2, 71, 71, ae, ba, 4f, 65, bc, ba, c7, 2f]
[2025-09-12T05:03:28.160345062Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[b5, db, 23, 24, 5b, 0c, fd, b5] reset_token=[1d, 30, c1, fe, 5c, 86, cd, d7, 9b, 9a, 4a, be, 22, 62, fb, a9]
[2025-09-12T05:03:28.160350352Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[b4, 2c, 82, 99, 8d, ef, cd, 62] reset_token=[78, 43, d1, 90, ba, d6, 26, 67, d3, f9, 0b, cd, 9f, 18, 8d, 91]
[2025-09-12T05:03:28.160360321Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 234 bytes
[2025-09-12T05:03:28.160364669Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.160367564Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.160375730Z DEBUG quiche_apps::common] sending HTTP request "GET /finfblkgxm\r\n"
[2025-09-12T05:03:28.160432576Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Initial version=1 dcid=3d2a85b239521c78 scid=76fc363e0aecdc3a57cf7a974bc698bd9e57f652 len=5 pn=1 
[2025-09-12T05:03:28.160437094Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=46 blocks=[0..0] ecn_counts=None
[2025-09-12T05:03:28.160447213Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=98.00264ms latest_rtt=32.798255ms srtt=32.798255ms min_rtt=32.798255ms rttvar=16.399127ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 412, delivered_time: Instant { tv_sec: 528, tv_nsec: 158327613 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 158327613 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 100.49 Kbps, is_app_limited: true, interval: 32.798255ms, delivered: 412, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 125325217 }), send_elapsed: 0ns, ack_elapsed: 32.798255ms, rtt: 32.798255ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514509, last_update: Instant { tv_sec: 528, tv_nsec: 158327583 }, next_time: Instant { tv_sec: 528, tv_nsec: 158327613 }, 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-12T05:03:28.160473983Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Handshake version=1 dcid=3d2a85b239521c78 scid=76fc363e0aecdc3a57cf7a974bc698bd9e57f652 len=45 pn=2 
[2025-09-12T05:03:28.160477580Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=34 blocks=[0..0] ecn_counts=None
[2025-09-12T05:03:28.160480786Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm CRYPTO off=0 len=36
[2025-09-12T05:03:28.160487679Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=98.331465ms latest_rtt=32.798255ms srtt=32.798255ms min_rtt=32.798255ms rttvar=16.399127ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 412, delivered_time: Instant { tv_sec: 528, tv_nsec: 158327613 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 158327613 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 100.49 Kbps, is_app_limited: true, interval: 32.798255ms, delivered: 412, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 125325217 }), send_elapsed: 0ns, ack_elapsed: 32.798255ms, rtt: 32.798255ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514509, last_update: Instant { tv_sec: 528, tv_nsec: 158492542 }, next_time: Instant { tv_sec: 528, tv_nsec: 158327613 }, max_datagram_size: 1350, last_packet_size: None, iv: 192.416µ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-12T05:03:28.160501364Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 dropped epoch 0 state
[2025-09-12T05:03:28.160516312Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=1165 pn=3 
[2025-09-12T05:03:28.160519558Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=24 blocks=[0..0] ecn_counts=None
[2025-09-12T05:03:28.160522724Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[a0, d1, 99, df, 1b, 32, dd, 58, 56, 50, 11, d7, 99, 4f, 3d, 41, c1, 17, c3, 56] reset_token=[e7, 25, 17, fe, cb, 5d, 2c, 04, 0a, 2a, 2f, 63, 64, 34, e1, 16]
[2025-09-12T05:03:28.160529888Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[d3, ff, ea, d9, c3, f3, db, f4, 5e, 86, 99, d4, 22, 7a, d6, 47, 4c, e8, 1d, f0] reset_token=[bc, 05, c3, f2, 82, 07, 88, 44, 2b, 7f, fb, 25, 6c, 9c, 14, de]
[2025-09-12T05:03:28.160536270Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[63, bd, 49, bd, 74, 2c, fd, 70, 59, 8b, fc, 38, 3b, b7, b7, 80, f9, ca, 56, 5b] reset_token=[e8, 1a, ee, a7, c3, 99, f3, 3f, 33, f3, b4, 43, 5e, 70, 61, 0e]
[2025-09-12T05:03:28.160542351Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[58, 4e, 7c, ed, a5, c5, 6b, a2, db, 6c, f8, df, 6b, 6e, b8, 8c, b5, 4c, 8d, 98] reset_token=[fa, 2a, c3, 21, 9f, 4c, 8e, 8e, de, 78, 62, 95, b6, a7, a1, 2c]
[2025-09-12T05:03:28.160552139Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-09-12T05:03:28.160555405Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm PADDING len=978
[2025-09-12T05:03:28.160564713Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=98.254411ms latest_rtt=32.798255ms srtt=32.798255ms min_rtt=32.798255ms rttvar=16.399127ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1290 app_limited=true congestion_recovery_start_time=None Rate { delivered: 412, delivered_time: Instant { tv_sec: 528, tv_nsec: 158327613 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 158327613 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 100.49 Kbps, is_app_limited: true, interval: 32.798255ms, delivered: 412, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 125325217 }), send_elapsed: 0ns, ack_elapsed: 32.798255ms, rtt: 32.798255ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1191, rate: 514509, last_update: Instant { tv_sec: 528, tv_nsec: 158492542 }, next_time: Instant { tv_sec: 528, tv_nsec: 158684958 }, max_datagram_size: 1350, last_packet_size: Some(1191), 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-12T05:03:28.160608565Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.160618393Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.192555820Z TRACE quiche_apps::client] got 406 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.192570928Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=385 pn=1 
[2025-09-12T05:03:28.192578582Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 path ID 0 now see SCID with seq num 1
[2025-09-12T05:03:28.192583100Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm HANDSHAKE_DONE
[2025-09-12T05:03:28.192588140Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 dropped epoch 1 state
[2025-09-12T05:03:28.192591827Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm ACK delay=16 blocks=[3..3] ecn_counts=None
[2025-09-12T05:03:28.192596205Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 packet newly acked 3
[2025-09-12T05:03:28.192601064Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm CRYPTO off=0 len=356
[2025-09-12T05:03:28.192631361Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-09-12T05:03:28.192646108Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 406 bytes
[2025-09-12T05:03:28.192651118Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.192654173Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.192657419Z INFO  quiche_apps::client] Retiring source CID 76fc363e0aecdc3a57cf7a974bc698bd9e57f652
[2025-09-12T05:03:28.192670895Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=45 pn=4 
[2025-09-12T05:03:28.192674611Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=12 blocks=[1..1] ecn_counts=None
[2025-09-12T05:03:28.192678178Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=1 conn_id=[c3, ec, 45, 64, 32, 6b, a0, bb, 56, 47, 6a, e2, da, f3, 9a, 50, 99, 17, f1, 0b] reset_token=[1a, 8d, d0, 79, db, a8, e1, 84, 1c, 6e, d1, f2, 9e, 69, ab, ca]
[2025-09-12T05:03:28.192691022Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=107.709769ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 71, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 190733054 }, next_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, max_datagram_size: 1350, last_packet_size: Some(71), 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-12T05:03:28.192767355Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.192777795Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.193647651Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.193655365Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=1329 pn=2 
[2025-09-12T05:03:28.193662188Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=0 len=1310 fin=false
[2025-09-12T05:03:28.193669511Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 1350 bytes
[2025-09-12T05:03:28.193673058Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.193675393Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.193679690Z TRACE quiche_apps::common] received 1310 bytes
[2025-09-12T05:03:28.193682125Z TRACE quiche_apps::common] stream 0 has 1310 bytes (fin? false)
[2025-09-12T05:03:28.193689218Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=5 
[2025-09-12T05:03:28.193691783Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=4 blocks=[1..2] ecn_counts=None
[2025-09-12T05:03:28.193698095Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=106.702897ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, max_datagram_size: 1350, last_packet_size: None, iv: 137.551µ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-12T05:03:28.193730055Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.193737288Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.194744020Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.194751745Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=1329 pn=3 
[2025-09-12T05:03:28.194757846Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=1310 len=1308 fin=false
[2025-09-12T05:03:28.194764709Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 1350 bytes
[2025-09-12T05:03:28.194768115Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.194770420Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.194774407Z TRACE quiche_apps::common] received 1308 bytes
[2025-09-12T05:03:28.194776691Z TRACE quiche_apps::common] stream 0 has 1308 bytes (fin? false)
[2025-09-12T05:03:28.194783414Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=6 
[2025-09-12T05:03:28.194785959Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=3 blocks=[1..3] ecn_counts=None
[2025-09-12T05:03:28.194791840Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=105.609182ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 192985459 }, 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-12T05:03:28.194823669Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.194830572Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.195846411Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.195851110Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=1329 pn=4 
[2025-09-12T05:03:28.195856610Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=2618 len=1308 fin=false
[2025-09-12T05:03:28.195863002Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 1350 bytes
[2025-09-12T05:03:28.195866218Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.195868542Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.195872240Z TRACE quiche_apps::common] received 1308 bytes
[2025-09-12T05:03:28.195874453Z TRACE quiche_apps::common] stream 0 has 1308 bytes (fin? false)
[2025-09-12T05:03:28.195880865Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=7 
[2025-09-12T05:03:28.195883380Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=3 blocks=[1..4] ecn_counts=None
[2025-09-12T05:03:28.195889031Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=104.511991ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 192985459 }, 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-12T05:03:28.195924557Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.195931941Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.196966896Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.196973749Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=1329 pn=5 
[2025-09-12T05:03:28.196979579Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=3926 len=1308 fin=false
[2025-09-12T05:03:28.196986292Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 1350 bytes
[2025-09-12T05:03:28.196989618Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.196991943Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.196995910Z TRACE quiche_apps::common] received 1308 bytes
[2025-09-12T05:03:28.196998094Z TRACE quiche_apps::common] stream 0 has 1308 bytes (fin? false)
[2025-09-12T05:03:28.197004686Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=8 
[2025-09-12T05:03:28.197007191Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=3 blocks=[1..5] ecn_counts=None
[2025-09-12T05:03:28.197013563Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=103.387469ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 192985459 }, 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-12T05:03:28.197044511Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.197050973Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.198055711Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.198062975Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=1329 pn=6 
[2025-09-12T05:03:28.198068886Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=5234 len=1308 fin=false
[2025-09-12T05:03:28.198075508Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 1350 bytes
[2025-09-12T05:03:28.198078854Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.198081199Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.198084996Z TRACE quiche_apps::common] received 1308 bytes
[2025-09-12T05:03:28.198087210Z TRACE quiche_apps::common] stream 0 has 1308 bytes (fin? false)
[2025-09-12T05:03:28.198093452Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=9 
[2025-09-12T05:03:28.198095906Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=3 blocks=[1..6] ecn_counts=None
[2025-09-12T05:03:28.198101547Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=102.299485ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 9, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 192985459 }, 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-12T05:03:28.198132685Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.198139438Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.199158473Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.199165556Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=1329 pn=7 
[2025-09-12T05:03:28.199171187Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=6542 len=1308 fin=false
[2025-09-12T05:03:28.199177519Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 1350 bytes
[2025-09-12T05:03:28.199180895Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.199183249Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.199186866Z TRACE quiche_apps::common] received 1308 bytes
[2025-09-12T05:03:28.199189100Z TRACE quiche_apps::common] stream 0 has 1308 bytes (fin? false)
[2025-09-12T05:03:28.199195222Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=10 
[2025-09-12T05:03:28.199197777Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=3 blocks=[1..7] ecn_counts=None
[2025-09-12T05:03:28.199203347Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=101.197695ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 10, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 192985459 }, 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-12T05:03:28.199238523Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.199245656Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.199492998Z TRACE quiche_apps::client] got 386 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.199497647Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=365 pn=8 
[2025-09-12T05:03:28.199502757Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=7850 len=342 fin=false
[2025-09-12T05:03:28.199509119Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 386 bytes
[2025-09-12T05:03:28.199512425Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.199514749Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.199518356Z TRACE quiche_apps::common] received 342 bytes
[2025-09-12T05:03:28.199520580Z TRACE quiche_apps::common] stream 0 has 342 bytes (fin? false)
[2025-09-12T05:03:28.199526972Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=11 
[2025-09-12T05:03:28.199529437Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=3 blocks=[1..8] ecn_counts=None
[2025-09-12T05:03:28.199535107Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=100.865905ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 11, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 192985459 }, 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-12T05:03:28.199564983Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.199571766Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.200595830Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.200607572Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=1329 pn=9 
[2025-09-12T05:03:28.200613373Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=8192 len=1308 fin=false
[2025-09-12T05:03:28.200619785Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 1350 bytes
[2025-09-12T05:03:28.200623221Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.200625546Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.200629283Z TRACE quiche_apps::common] received 1308 bytes
[2025-09-12T05:03:28.200631507Z TRACE quiche_apps::common] stream 0 has 1308 bytes (fin? false)
[2025-09-12T05:03:28.200649931Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=12 
[2025-09-12T05:03:28.200652666Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=5 blocks=[1..9] ecn_counts=None
[2025-09-12T05:03:28.200659118Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=99.741934ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 12, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 192985459 }, 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-12T05:03:28.200690317Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.200697300Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.201245785Z TRACE quiche_apps::client] got 784 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.201250374Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=763 pn=10 
[2025-09-12T05:03:28.201255984Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=9500 len=740 fin=false
[2025-09-12T05:03:28.201262316Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 784 bytes
[2025-09-12T05:03:28.201265642Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.201267977Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.201271583Z TRACE quiche_apps::common] received 740 bytes
[2025-09-12T05:03:28.201273798Z TRACE quiche_apps::common] stream 0 has 740 bytes (fin? false)
[2025-09-12T05:03:28.201280170Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=5 pn=13 
[2025-09-12T05:03:28.201282634Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm ACK delay=3 blocks=[1..10] ecn_counts=None
[2025-09-12T05:03:28.201288565Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=99.112487ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=71 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 3, last_sent_packet: 13, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 191753492 }, next_time: Instant { tv_sec: 528, tv_nsec: 192985459 }, 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-12T05:03:28.201323891Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.201331185Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.223229053Z TRACE quiche_apps::client] got 43 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.223245163Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx pkt Short dcid=a0d199df1b32dd58565011d7994f3d41c117c356 key_phase=false len=22 pn=11 
[2025-09-12T05:03:28.223253478Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 rx frm STREAM id=0 off=10240 len=0 fin=true
[2025-09-12T05:03:28.223264529Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 43 bytes
[2025-09-12T05:03:28.223269158Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.223272113Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.223278395Z TRACE quiche_apps::common] received 0 bytes
[2025-09-12T05:03:28.223281420Z TRACE quiche_apps::common] stream 0 has 0 bytes (fin? true)
[2025-09-12T05:03:28.223284356Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-12T05:03:28.223287242Z INFO  quiche_apps::common] 1/1 response(s) received in 95.885765ms, closing...
[2025-09-12T05:03:28.223296960Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx pkt Short dcid=3d2a85b239521c78 key_phase=false len=10 pn=14 
[2025-09-12T05:03:28.223300376Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-12T05:03:28.223309062Z TRACE quiche::recovery::congestion::recovery] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 timer=107.719047ms latest_rtt=31.951987ms srtt=32.692471ms min_rtt=31.951987ms rttvar=12.510912ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=107 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1603, delivered_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, first_sent_time: Instant { tv_sec: 528, tv_nsec: 190733054 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 3, rate_sample: RateSample { bandwidth: 294.90 Kbps, is_app_limited: true, interval: 32.309332ms, delivered: 1191, prior_delivered: 412, prior_time: Some(Instant { tv_sec: 528, tv_nsec: 158327613 }), send_elapsed: 357.345µs, ack_elapsed: 32.309332ms, rtt: 31.951987ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 36, rate: 516173, last_update: Instant { tv_sec: 528, tv_nsec: 221360382 }, next_time: Instant { tv_sec: 528, tv_nsec: 221360382 }, max_datagram_size: 1350, last_packet_size: Some(36), 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-12T05:03:28.223353525Z TRACE quiche_apps::client] written 36 bytes from 0.0.0.0:34387 to 193.167.100.100:443
[2025-09-12T05:03:28.223370166Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.249804206Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.249818152Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 44 bytes
[2025-09-12T05:03:28.249823222Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.249826428Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.249831217Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.253823470Z TRACE quiche_apps::client] got 47 bytes from 193.167.100.100:443 to 0.0.0.0:34387
[2025-09-12T05:03:28.253837496Z TRACE quiche_apps::client] 0.0.0.0:34387: processed 47 bytes
[2025-09-12T05:03:28.253842616Z TRACE quiche_apps::client] 0.0.0.0:34387: recv() would block
[2025-09-12T05:03:28.253845782Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.253850541Z TRACE quiche_apps::client] 0.0.0.0:34387 -> 193.167.100.100:443: done writing
[2025-09-12T05:03:28.472132586Z TRACE quiche_apps::client] timed out
[2025-09-12T05:03:28.472163323Z TRACE quiche] 76fc363e0aecdc3a57cf7a974bc698bd9e57f652 draining timeout expired
[2025-09-12T05:03:28.472212696Z TRACE quiche_apps::client] done reading
[2025-09-12T05:03:28.472218487Z INFO  quiche_apps::client] connection closed, recv=14 sent=15 lost=0 retrans=0 sent_bytes=2148 recv_bytes=12503 lost_bytes=0 [local_addr=0.0.0.0:34387 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=14 sent=15 lost=0 retrans=0 rtt=32.692471ms min_rtt=Some(31.951987ms) rttvar=12.510912ms cwnd=13500 sent_bytes=2148 recv_bytes=12503 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=36862]