[2025-04-30T16:43:14.697684688Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T16:43:14.697996390Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:34490 with scid ddb8a09c34d1d28f8b64a05945917fe90ac74e24
[2025-04-30T16:43:14.698044760Z TRACE quiche::tls] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 write message lvl=Initial len=434
[2025-04-30T16:43:14.698063936Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Initial version=1 dcid=6213fe65498909f694d19b9441d41f21 scid=ddb8a09c34d1d28f8b64a05945917fe90ac74e24 len=438 pn=0 
[2025-04-30T16:43:14.698067493Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm CRYPTO off=0 len=434
[2025-04-30T16:43:14.698082531Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=998.920141ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=501 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 313, tv_nsec: 775617866 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 775617866 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 313, tv_nsec: 775617866 }, next_time: Instant { tv_sec: 313, tv_nsec: 775617866 }, 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-04-30T16:43:14.698184101Z TRACE quiche_apps::client] written 1200
[2025-04-30T16:43:14.731378197Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.731414275Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Initial version=1 dcid=ddb8a09c34d1d28f8b64a05945917fe90ac74e24 scid=cac0892d token= len=868 pn=0 
[2025-04-30T16:43:14.731428461Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T16:43:14.731434372Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 packet newly acked 0
[2025-04-30T16:43:14.731443079Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm PADDING len=745
[2025-04-30T16:43:14.731446795Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm CRYPTO off=0 len=96
[2025-04-30T16:43:14.731538106Z TRACE quiche::tls] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 set write secret lvl=Handshake
[2025-04-30T16:43:14.731547874Z TRACE quiche::tls] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 set read secret lvl=Handshake
[2025-04-30T16:43:14.731570446Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Handshake version=1 dcid=ddb8a09c34d1d28f8b64a05945917fe90ac74e24 scid=cac0892d len=186 pn=0 
[2025-04-30T16:43:14.731576117Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm CRYPTO off=0 len=164
[2025-04-30T16:43:14.731606894Z TRACE quiche::tls] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 write message lvl=Handshake len=36
[2025-04-30T16:43:14.731610371Z TRACE quiche::tls] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 set write secret lvl=OneRTT
[2025-04-30T16:43:14.731617424Z TRACE quiche::tls] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 set read secret lvl=OneRTT
[2025-04-30T16:43:14.731633344Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 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(6213fe65498909f694d19b9441d41f21), max_idle_timeout: 30000, stateless_reset_token: Some(314585574972923218826215510100152144379), 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(cac0892d), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T16:43:14.731662478Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=ddb8a09c34d1d28f8b64a05945917fe90ac74e24 key_phase=false len=138 pn=0 
[2025-04-30T16:43:14.731669502Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[26, a4, 1d, bc] reset_token=[57, 63, 7d, 06, ab, a6, 0e, 57, 25, d3, 82, 4f, 97, 6c, 46, b1]
[2025-04-30T16:43:14.731676865Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[67, d6, 44, fc] reset_token=[75, ba, 70, 23, e5, 93, 4c, 95, ab, d8, 11, d0, 8d, ba, b0, 10]
[2025-04-30T16:43:14.731682245Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[1a, c4, bf, d1] reset_token=[08, 83, 1c, ca, d9, 35, 0f, ec, c8, f1, c1, f6, e3, 86, c5, 38]
[2025-04-30T16:43:14.731687465Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[24, c5, 44, ec] reset_token=[b5, 9e, 08, 85, 3a, e6, 95, 0e, 8e, 69, 98, 67, 7c, 0f, 6f, ab]
[2025-04-30T16:43:14.731693206Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[a5, 51, e7, e3] reset_token=[27, de, ae, ad, 6a, 96, ac, cd, f8, 70, e3, 7e, bd, cd, b4, 36]
[2025-04-30T16:43:14.731702062Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.731707302Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.731710558Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.731719515Z DEBUG quiche_apps::common] sending HTTP request "GET /dqcnvgjlsn\r\n"
[2025-04-30T16:43:14.731780218Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Initial version=1 dcid=cac0892d scid=ddb8a09c34d1d28f8b64a05945917fe90ac74e24 len=5 pn=1 
[2025-04-30T16:43:14.731784787Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=45 blocks=[0..0] ecn_counts=None
[2025-04-30T16:43:14.731794004Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=99.837205ms latest_rtt=33.407135ms srtt=33.407135ms min_rtt=33.407135ms rttvar=16.703567ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 501, delivered_time: Instant { tv_sec: 313, tv_nsec: 809246174 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 809246174 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14996, is_app_limited: true, interval: 33.407135ms, delivered: 501, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 775617866 }), send_elapsed: 0ns, ack_elapsed: 33.407135ms, rtt: 33.407135ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 505131, last_update: Instant { tv_sec: 313, tv_nsec: 809246134 }, next_time: Instant { tv_sec: 313, tv_nsec: 809246174 }, 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-04-30T16:43:14.731815474Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Handshake version=1 dcid=cac0892d scid=ddb8a09c34d1d28f8b64a05945917fe90ac74e24 len=45 pn=2 
[2025-04-30T16:43:14.731824110Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=30 blocks=[0..0] ecn_counts=None
[2025-04-30T16:43:14.731827437Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm CRYPTO off=0 len=36
[2025-04-30T16:43:14.731834480Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=100.158986ms latest_rtt=33.407135ms srtt=33.407135ms min_rtt=33.407135ms rttvar=16.703567ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=95 app_limited=true congestion_recovery_start_time=None Rate { delivered: 501, delivered_time: Instant { tv_sec: 313, tv_nsec: 809246174 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 809246174 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14996, is_app_limited: true, interval: 33.407135ms, delivered: 501, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 775617866 }), send_elapsed: 0ns, ack_elapsed: 33.407135ms, rtt: 33.407135ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 505131, last_update: Instant { tv_sec: 313, tv_nsec: 809386887 }, next_time: Instant { tv_sec: 313, tv_nsec: 809246174 }, max_datagram_size: 1350, last_packet_size: None, iv: 188.07µ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-04-30T16:43:14.731847514Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 dropped epoch 0 state
[2025-04-30T16:43:14.731853054Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13405 out_len=1199 left=1172
[2025-04-30T16:43:14.731866329Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=1177 pn=3 
[2025-04-30T16:43:14.731869615Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=23 blocks=[0..0] ecn_counts=None
[2025-04-30T16:43:14.731872891Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[79, ea, b8, 4a, 2a, 5c, 30, 9a, 6c, aa, ad, 26, a4, c2, 14, af, e7, 5f, 8e, 86] reset_token=[6a, 43, 4d, ba, 8d, b5, 86, 24, a3, 9d, 6d, 0c, ae, 63, d8, a9]
[2025-04-30T16:43:14.731879774Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[4d, 30, 78, 48, a9, 02, 20, f5, 49, 52, 96, df, 1f, 68, 7e, 69, 30, cf, e1, 06] reset_token=[bf, bc, 81, 48, 6b, 53, 8d, 4b, 99, 55, ab, fd, 7f, 8c, af, 37]
[2025-04-30T16:43:14.731887008Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[99, 3b, d5, 87, 73, 4f, 52, 0b, 74, 70, 3a, 3f, f1, 9a, 91, 01, 63, bf, 87, 51] reset_token=[60, b6, 84, 84, a3, db, df, 94, bd, 8a, 08, 83, 9f, b6, 87, 2e]
[2025-04-30T16:43:14.731893370Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T16:43:14.731896716Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm PADDING len=1030
[2025-04-30T16:43:14.731905382Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=100.088034ms latest_rtt=33.407135ms srtt=33.407135ms min_rtt=33.407135ms rttvar=16.703567ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1294 app_limited=true congestion_recovery_start_time=None Rate { delivered: 501, delivered_time: Instant { tv_sec: 313, tv_nsec: 809246174 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 809246174 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14996, is_app_limited: true, interval: 33.407135ms, delivered: 501, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 775617866 }), send_elapsed: 0ns, ack_elapsed: 33.407135ms, rtt: 33.407135ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1199, rate: 505131, last_update: Instant { tv_sec: 313, tv_nsec: 809386887 }, next_time: Instant { tv_sec: 313, tv_nsec: 809574957 }, max_datagram_size: 1350, last_packet_size: Some(1199), 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-04-30T16:43:14.731948603Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.731953652Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.764125820Z TRACE quiche_apps::client] got 278 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.764141600Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=257 pn=1 
[2025-04-30T16:43:14.764149715Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 path ID 0 now see SCID with seq num 1
[2025-04-30T16:43:14.764154794Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm ACK delay=47 blocks=[3..3] ecn_counts=None
[2025-04-30T16:43:14.764159383Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 packet newly acked 3
[2025-04-30T16:43:14.764164793Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm CRYPTO off=0 len=138
[2025-04-30T16:43:14.764188086Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm NEW_TOKEN len=86
[2025-04-30T16:43:14.764191613Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm HANDSHAKE_DONE
[2025-04-30T16:43:14.764195681Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 dropped epoch 1 state
[2025-04-30T16:43:14.764199347Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-04-30T16:43:14.764215347Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 278 bytes
[2025-04-30T16:43:14.764220156Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.764223332Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.764226919Z INFO  quiche_apps::client] Retiring source CID ddb8a09c34d1d28f8b64a05945917fe90ac74e24
[2025-04-30T16:43:14.764238501Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1323
[2025-04-30T16:43:14.764243880Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=45 pn=4 
[2025-04-30T16:43:14.764247167Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=12 blocks=[1..1] ecn_counts=None
[2025-04-30T16:43:14.764250713Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=1 conn_id=[4f, 8f, b8, d5, 6f, 61, ce, 88, 58, f8, 2a, ff, 82, f4, 99, cc, e1, 48, c0, 4a] reset_token=[12, b0, 27, 82, 72, 73, 83, d8, 69, 52, e0, c5, ef, de, 9c, 4c]
[2025-04-30T16:43:14.764263167Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=110.563355ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 67, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 841849357 }, next_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, 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-04-30T16:43:14.764322567Z TRACE quiche_apps::client] written 67 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.764328669Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.765293612Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.765303129Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=1259 pn=2 
[2025-04-30T16:43:14.765311204Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=0 len=1239 fin=false
[2025-04-30T16:43:14.765319450Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.765323237Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.765325752Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.765330561Z TRACE quiche_apps::common] received 1239 bytes
[2025-04-30T16:43:14.765333295Z TRACE quiche_apps::common] stream 0 has 1239 bytes (fin? false)
[2025-04-30T16:43:14.765340268Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1323
[2025-04-30T16:43:14.765343835Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=5 pn=5 
[2025-04-30T16:43:14.765346290Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=4 blocks=[1..2] ecn_counts=None
[2025-04-30T16:43:14.765373190Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=109.453422ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 842951446 }, next_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, max_datagram_size: 1350, last_packet_size: None, iv: 132.029µ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-04-30T16:43:14.765433623Z TRACE quiche_apps::client] written 27 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.765441307Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.766331560Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.766336429Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=1259 pn=3 
[2025-04-30T16:43:14.766342821Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=1239 len=1237 fin=false
[2025-04-30T16:43:14.766358501Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.766367167Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.766369591Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.766373799Z TRACE quiche_apps::common] received 1237 bytes
[2025-04-30T16:43:14.766376054Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false)
[2025-04-30T16:43:14.766382215Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1323
[2025-04-30T16:43:14.766385441Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=5 pn=6 
[2025-04-30T16:43:14.766404927Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=5 blocks=[1..3] ecn_counts=None
[2025-04-30T16:43:14.766411520Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=108.415282ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 842951446 }, next_time: Instant { tv_sec: 313, tv_nsec: 844126102 }, 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-04-30T16:43:14.766443359Z TRACE quiche_apps::client] written 27 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.766447166Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.767711027Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.767718030Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=1259 pn=4 
[2025-04-30T16:43:14.767723821Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=2476 len=1237 fin=false
[2025-04-30T16:43:14.767730373Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.767733830Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.767736124Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.767740041Z TRACE quiche_apps::common] received 1237 bytes
[2025-04-30T16:43:14.767742246Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false)
[2025-04-30T16:43:14.767748347Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1323
[2025-04-30T16:43:14.767751433Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=5 pn=7 
[2025-04-30T16:43:14.767753817Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=3 blocks=[1..4] ecn_counts=None
[2025-04-30T16:43:14.767759287Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=107.067585ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 842951446 }, next_time: Instant { tv_sec: 313, tv_nsec: 844126102 }, 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-04-30T16:43:14.767795695Z TRACE quiche_apps::client] written 27 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.767799482Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.768443275Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.768451580Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=1259 pn=5 
[2025-04-30T16:43:14.768458273Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=3713 len=1237 fin=false
[2025-04-30T16:43:14.768465777Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.768469915Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.768472830Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.768477579Z TRACE quiche_apps::common] received 1237 bytes
[2025-04-30T16:43:14.768480324Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false)
[2025-04-30T16:43:14.768487117Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1323
[2025-04-30T16:43:14.768490864Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=5 pn=8 
[2025-04-30T16:43:14.768493830Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=4 blocks=[1..5] ecn_counts=None
[2025-04-30T16:43:14.768501143Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=106.325649ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 842951446 }, next_time: Instant { tv_sec: 313, tv_nsec: 844126102 }, 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-04-30T16:43:14.768537862Z TRACE quiche_apps::client] written 27 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.768542270Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.769463341Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.769474722Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=1259 pn=6 
[2025-04-30T16:43:14.769480142Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=4950 len=1237 fin=false
[2025-04-30T16:43:14.769486303Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.769489710Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.769492084Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.769495871Z TRACE quiche_apps::common] received 1237 bytes
[2025-04-30T16:43:14.769498035Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false)
[2025-04-30T16:43:14.769503456Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1323
[2025-04-30T16:43:14.769506481Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=5 pn=9 
[2025-04-30T16:43:14.769508815Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=3 blocks=[1..6] ecn_counts=None
[2025-04-30T16:43:14.769514176Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=105.312677ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 9, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 842951446 }, next_time: Instant { tv_sec: 313, tv_nsec: 844126102 }, 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-04-30T16:43:14.769544221Z TRACE quiche_apps::client] written 27 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.769547738Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.770510837Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.770517630Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=1259 pn=7 
[2025-04-30T16:43:14.770522910Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=6187 len=1237 fin=false
[2025-04-30T16:43:14.770528851Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.770532157Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.770534542Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.770538148Z TRACE quiche_apps::common] received 1237 bytes
[2025-04-30T16:43:14.770540302Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false)
[2025-04-30T16:43:14.770545672Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1323
[2025-04-30T16:43:14.770548658Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=5 pn=10 
[2025-04-30T16:43:14.770551022Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=3 blocks=[1..7] ecn_counts=None
[2025-04-30T16:43:14.770561231Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=104.265601ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 10, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 842951446 }, next_time: Instant { tv_sec: 313, tv_nsec: 844126102 }, 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-04-30T16:43:14.770591618Z TRACE quiche_apps::client] written 27 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.770595245Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.771558705Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.771565548Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=1259 pn=8 
[2025-04-30T16:43:14.771570827Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=7424 len=1237 fin=false
[2025-04-30T16:43:14.771576858Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.771580175Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.771582609Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.771586386Z TRACE quiche_apps::common] received 1237 bytes
[2025-04-30T16:43:14.771588580Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false)
[2025-04-30T16:43:14.771607306Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1323
[2025-04-30T16:43:14.771610652Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=5 pn=11 
[2025-04-30T16:43:14.771613046Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=5 blocks=[1..8] ecn_counts=None
[2025-04-30T16:43:14.771618616Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=103.208246ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 11, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 842951446 }, next_time: Instant { tv_sec: 313, tv_nsec: 844126102 }, 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-04-30T16:43:14.771652330Z TRACE quiche_apps::client] written 27 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.771656177Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.772607003Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.772613896Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=1259 pn=9 
[2025-04-30T16:43:14.772619436Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=8661 len=1237 fin=false
[2025-04-30T16:43:14.772625668Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 1280 bytes
[2025-04-30T16:43:14.772629054Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.772631368Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.772635146Z TRACE quiche_apps::common] received 1237 bytes
[2025-04-30T16:43:14.772637339Z TRACE quiche_apps::common] stream 0 has 1237 bytes (fin? false)
[2025-04-30T16:43:14.772642930Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1323
[2025-04-30T16:43:14.772645886Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=5 pn=12 
[2025-04-30T16:43:14.772648220Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm ACK delay=3 blocks=[1..9] ecn_counts=None
[2025-04-30T16:43:14.772654001Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=102.172901ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 3, last_sent_packet: 12, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 842951446 }, next_time: Instant { tv_sec: 313, tv_nsec: 844126102 }, 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-04-30T16:43:14.772683496Z TRACE quiche_apps::client] written 27 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.772687062Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:14.772937019Z TRACE quiche_apps::client] got 385 bytes from 193.167.100.100:443 to 0.0.0.0:34490
[2025-04-30T16:43:14.772941167Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx pkt Short dcid=79eab84a2a5c309a6caaad26a4c214afe75f8e86 key_phase=false len=364 pn=10 
[2025-04-30T16:43:14.772945916Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 rx frm STREAM id=0 off=9898 len=342 fin=true
[2025-04-30T16:43:14.772951917Z TRACE quiche_apps::client] 0.0.0.0:34490: processed 385 bytes
[2025-04-30T16:43:14.772955213Z TRACE quiche_apps::client] 0.0.0.0:34490: recv() would block
[2025-04-30T16:43:14.772957588Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:14.772962477Z TRACE quiche_apps::common] received 342 bytes
[2025-04-30T16:43:14.772964681Z TRACE quiche_apps::common] stream 0 has 342 bytes (fin? true)
[2025-04-30T16:43:14.772971584Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T16:43:14.772974079Z INFO  quiche_apps::common] 1/1 response(s) received in 74.785138ms, closing...
[2025-04-30T16:43:14.772979839Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1328
[2025-04-30T16:43:14.772983035Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx pkt Short dcid=cac0892d key_phase=false len=10 pn=13 
[2025-04-30T16:43:14.772985450Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T16:43:14.772992002Z TRACE quiche::recovery::congestion::recovery] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 timer=110.577802ms latest_rtt=32.179172ms srtt=33.253639ms min_rtt=32.179172ms rttvar=12.834665ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1700, delivered_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 841849357 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36883, is_app_limited: true, interval: 32.507955ms, delivered: 1199, prior_delivered: 501, prior_time: Some(Instant { tv_sec: 313, tv_nsec: 809246174 }), send_elapsed: 328.783µs, ack_elapsed: 32.507955ms, rtt: 32.179172ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507463, last_update: Instant { tv_sec: 313, tv_nsec: 850592319 }, next_time: Instant { tv_sec: 313, tv_nsec: 844126102 }, max_datagram_size: 1350, last_packet_size: None, iv: 63.059µ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-04-30T16:43:14.773022750Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:34490 to 193.167.100.100:443
[2025-04-30T16:43:14.773026116Z TRACE quiche_apps::client] 0.0.0.0:34490 -> 193.167.100.100:443: done writing
[2025-04-30T16:43:15.027332980Z TRACE quiche_apps::client] timed out
[2025-04-30T16:43:15.027362235Z TRACE quiche] ddb8a09c34d1d28f8b64a05945917fe90ac74e24 draining timeout expired
[2025-04-30T16:43:15.027439539Z TRACE quiche_apps::client] done reading
[2025-04-30T16:43:15.027444880Z INFO  quiche_apps::client] connection closed, recv=13 sent=14 lost=0 retrans=0 sent_bytes=2166 recv_bytes=12183 lost_bytes=0 [local_addr=0.0.0.0:34490 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=13 sent=14 lost=0 retrans=0 rtt=33.253639ms min_rtt=Some(32.179172ms) rttvar=12.834665ms cwnd=13500 sent_bytes=2166 recv_bytes=12183 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=36883]