[2025-04-30T18:25:45.659783621Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T18:25:45.660099021Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:56474 with scid dfb2629e0b68476ad0678502d3cb8bf36f40d227
[2025-04-30T18:25:45.660148864Z TRACE quiche::tls] dfb2629e0b68476ad0678502d3cb8bf36f40d227 write message lvl=Initial len=489
[2025-04-30T18:25:45.660164794Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Initial version=1 dcid=9d9eec48ccfe7487a1c712f8f09c4a54 scid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 len=493 pn=0 
[2025-04-30T18:25:45.660167930Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm CRYPTO off=0 len=489
[2025-04-30T18:25:45.660182076Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=998.92523ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=556 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 297, tv_nsec: 810131909 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 810131909 }, 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: 297, tv_nsec: 810131909 }, next_time: Instant { tv_sec: 297, tv_nsec: 810131909 }, 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-30T18:25:45.660283305Z TRACE quiche_apps::client] written 1200
[2025-04-30T18:25:45.693349951Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.693382662Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Initial version=1 dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 scid=747b303c77f5396506b592183e6e5fc5dcf0156e token= len=122 pn=0 
[2025-04-30T18:25:45.693399183Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm ACK delay=41 blocks=[0..0] ecn_counts=None
[2025-04-30T18:25:45.693406166Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 packet newly acked 0
[2025-04-30T18:25:45.693412909Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm CRYPTO off=0 len=96
[2025-04-30T18:25:45.693518797Z TRACE quiche::tls] dfb2629e0b68476ad0678502d3cb8bf36f40d227 set write secret lvl=Handshake
[2025-04-30T18:25:45.693530438Z TRACE quiche::tls] dfb2629e0b68476ad0678502d3cb8bf36f40d227 set read secret lvl=Handshake
[2025-04-30T18:25:45.693561476Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Handshake version=1 dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 scid=747b303c77f5396506b592183e6e5fc5dcf0156e len=169 pn=1 
[2025-04-30T18:25:45.693568109Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm CRYPTO off=0 len=148
[2025-04-30T18:25:45.693599848Z TRACE quiche::tls] dfb2629e0b68476ad0678502d3cb8bf36f40d227 write message lvl=Handshake len=36
[2025-04-30T18:25:45.693603595Z TRACE quiche::tls] dfb2629e0b68476ad0678502d3cb8bf36f40d227 set write secret lvl=OneRTT
[2025-04-30T18:25:45.693611119Z TRACE quiche::tls] dfb2629e0b68476ad0678502d3cb8bf36f40d227 set read secret lvl=OneRTT
[2025-04-30T18:25:45.693627731Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=true TransportParams { original_destination_connection_id: Some(9d9eec48ccfe7487a1c712f8f09c4a54), max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1350, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(747b303c77f5396506b592183e6e5fc5dcf0156e), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T18:25:45.693665661Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=0000000000000000000000000000000000000000 key_phase=true len=789 pn=2859676280 
[2025-04-30T18:25:45.693671773Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 dropped invalid packet
[2025-04-30T18:25:45.693675079Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1200 bytes
[2025-04-30T18:25:45.693680279Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.693683555Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.693711287Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm SETTINGS stream=2 len=18
[2025-04-30T18:25:45.693726826Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 open GREASE stream 14
[2025-04-30T18:25:45.693735342Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm GREASE stream=0 len=0
[2025-04-30T18:25:45.693740882Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm GREASE stream=0 len=18
[2025-04-30T18:25:45.693748085Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm HEADERS stream=0 len=30 fin=true
[2025-04-30T18:25:45.693754488Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /dqzgsojyoa", "user-agent: quiche"]
[2025-04-30T18:25:45.693841791Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Initial version=1 dcid=747b303c77f5396506b592183e6e5fc5dcf0156e scid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 len=5 pn=1 
[2025-04-30T18:25:45.693845778Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=57 blocks=[0..0] ecn_counts=None
[2025-04-30T18:25:45.693855146Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=99.322473ms latest_rtt=33.267561ms srtt=33.267561ms min_rtt=33.267561ms rttvar=16.63378ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 556, delivered_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 16712, is_app_limited: true, interval: 33.267561ms, delivered: 556, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 810131909 }), send_elapsed: 0ns, ack_elapsed: 33.267561ms, rtt: 33.267561ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507250, last_update: Instant { tv_sec: 297, tv_nsec: 843649428 }, next_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, 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-30T18:25:45.693876055Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Handshake version=1 dcid=747b303c77f5396506b592183e6e5fc5dcf0156e scid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 len=45 pn=2 
[2025-04-30T18:25:45.693878960Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=39 blocks=[1..1] ecn_counts=None
[2025-04-30T18:25:45.693881525Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm CRYPTO off=0 len=36
[2025-04-30T18:25:45.693887336Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=99.748109ms latest_rtt=33.267561ms srtt=33.267561ms min_rtt=33.267561ms rttvar=16.63378ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=111 app_limited=true congestion_recovery_start_time=None Rate { delivered: 556, delivered_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 16712, is_app_limited: true, interval: 33.267561ms, delivered: 556, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 810131909 }), send_elapsed: 0ns, ack_elapsed: 33.267561ms, rtt: 33.267561ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507250, last_update: Instant { tv_sec: 297, tv_nsec: 843856896 }, next_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, max_datagram_size: 1350, last_packet_size: None, iv: 218.827µ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-30T18:25:45.693903476Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 dropped epoch 0 state
[2025-04-30T18:25:45.693908606Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13389 out_len=1167 left=1129
[2025-04-30T18:25:45.693919907Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=1129 pn=3 
[2025-04-30T18:25:45.693922432Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[75, 18, 6d, 42, 3b, 83, e2, 2a, b6, 32, 45, da, c7, 47, 4c, c7, fa, 50, 3e, c9] reset_token=[5e, 5c, 07, 58, a6, 0f, 79, fa, 58, 75, 66, 29, 38, 3f, d0, 4b]
[2025-04-30T18:25:45.693929856Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[d9, d1, f6, 51, 8a, 78, 67, df, 8d, 93, d0, 46, bd, 9d, 16, 46, 76, 2f, 38, 92] reset_token=[0f, c6, b0, c0, 40, 1f, 6d, f9, d0, 19, d6, 79, d3, 03, 98, 1c]
[2025-04-30T18:25:45.693935195Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[02, e1, aa, b0, ae, 5d, 30, bf, 27, ea, f7, b7, 4e, c1, f0, df, 10, 07, a0, e6] reset_token=[47, 83, 0b, 49, 63, f2, 0d, dd, 46, e7, 1f, 23, 9f, 40, ae, ab]
[2025-04-30T18:25:45.693940385Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[37, d7, 7f, c2, 39, d5, 3d, 87, a2, 3d, 02, e3, ee, fe, 2b, 9a, b1, 48, f8, 7b] reset_token=[45, 47, f1, 38, 0a, cd, 2d, 3f, 7d, 35, f1, 40, c0, dc, 7c, 9d]
[2025-04-30T18:25:45.693945625Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[94, f3, db, 10, bd, 3d, f3, 99, e5, ac, 1b, 14, bf, 4f, 5c, 8e, c3, 7b, 76, 7c] reset_token=[c6, 89, a6, e9, dc, 4a, 5f, f4, 09, 55, 97, 52, ca, 41, 24, 1d]
[2025-04-30T18:25:45.693950655Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[1f, cc, e2, 88, 22, a1, 73, 81, 11, 71, e7, ec, 67, 53, 78, f4, cd, 8f, 28, 2a] reset_token=[cf, f6, f6, 23, 62, ae, dc, b0, 5a, b9, b3, 59, aa, de, 38, b3]
[2025-04-30T18:25:45.693955564Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[90, bd, 91, bd, f6, cc, 55, 1c, 40, 63, 15, 2b, c8, c0, c1, 13, 7a, 90, ff, 0e] reset_token=[1f, f4, 3f, a2, 18, 0e, 5f, 39, d2, 94, d7, 67, f3, 89, 3d, a9]
[2025-04-30T18:25:45.693960663Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T18:25:45.693963188Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm PADDING len=825
[2025-04-30T18:25:45.694024833Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=99.609239ms latest_rtt=33.267561ms srtt=33.267561ms min_rtt=33.267561ms rttvar=16.63378ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1278 app_limited=true congestion_recovery_start_time=None Rate { delivered: 556, delivered_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 16712, is_app_limited: true, interval: 33.267561ms, delivered: 556, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 810131909 }), send_elapsed: 0ns, ack_elapsed: 33.267561ms, rtt: 33.267561ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1167, rate: 507250, last_update: Instant { tv_sec: 297, tv_nsec: 843856896 }, next_time: Instant { tv_sec: 297, tv_nsec: 844075723 }, max_datagram_size: 1350, last_packet_size: Some(1167), 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-30T18:25:45.694089484Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.694101256Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12222 out_len=1350 left=1312
[2025-04-30T18:25:45.694106987Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=6 pn=4 
[2025-04-30T18:25:45.694110774Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T18:25:45.694119090Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=99.516015ms latest_rtt=33.267561ms srtt=33.267561ms min_rtt=33.267561ms rttvar=16.63378ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1322 app_limited=true congestion_recovery_start_time=None Rate { delivered: 556, delivered_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 16712, is_app_limited: true, interval: 33.267561ms, delivered: 556, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 810131909 }), send_elapsed: 0ns, ack_elapsed: 33.267561ms, rtt: 33.267561ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507250, last_update: Instant { tv_sec: 297, tv_nsec: 844120398 }, next_time: Instant { tv_sec: 297, tv_nsec: 844075723 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.387383ms, 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-30T18:25:45.694165266Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.694176006Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12178 out_len=1350 left=1312
[2025-04-30T18:25:45.694182368Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=6 pn=5 
[2025-04-30T18:25:45.694186405Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T18:25:45.694197626Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=99.437518ms latest_rtt=33.267561ms srtt=33.267561ms min_rtt=33.267561ms rttvar=16.63378ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1366 app_limited=true congestion_recovery_start_time=None Rate { delivered: 556, delivered_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 16712, is_app_limited: true, interval: 33.267561ms, delivered: 556, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 810131909 }), send_elapsed: 0ns, ack_elapsed: 33.267561ms, rtt: 33.267561ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 44, rate: 507250, last_update: Instant { tv_sec: 297, tv_nsec: 844120398 }, next_time: Instant { tv_sec: 297, tv_nsec: 846582802 }, max_datagram_size: 1350, last_packet_size: Some(44), 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-30T18:25:45.694243272Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.694254693Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12134 out_len=1350 left=1312
[2025-04-30T18:25:45.694261636Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=73 pn=6 
[2025-04-30T18:25:45.694265503Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T18:25:45.694276584Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=99.35841ms latest_rtt=33.267561ms srtt=33.267561ms min_rtt=33.267561ms rttvar=16.63378ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1477 app_limited=true congestion_recovery_start_time=None Rate { delivered: 556, delivered_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 16712, is_app_limited: true, interval: 33.267561ms, delivered: 556, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 810131909 }), send_elapsed: 0ns, ack_elapsed: 33.267561ms, rtt: 33.267561ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 507250, last_update: Instant { tv_sec: 297, tv_nsec: 844273585 }, next_time: Instant { tv_sec: 297, tv_nsec: 846582802 }, max_datagram_size: 1350, last_packet_size: None, iv: 305.569µ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-30T18:25:45.694312311Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.694322410Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12023 out_len=1350 left=1312
[2025-04-30T18:25:45.694328141Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=31 pn=7 
[2025-04-30T18:25:45.694331948Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T18:25:45.694342187Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=99.292818ms latest_rtt=33.267561ms srtt=33.267561ms min_rtt=33.267561ms rttvar=16.63378ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1546 app_limited=true congestion_recovery_start_time=None Rate { delivered: 556, delivered_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 843649468 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 16712, is_app_limited: true, interval: 33.267561ms, delivered: 556, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 810131909 }), send_elapsed: 0ns, ack_elapsed: 33.267561ms, rtt: 33.267561ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 69, rate: 507250, last_update: Instant { tv_sec: 297, tv_nsec: 844273585 }, next_time: Instant { tv_sec: 297, tv_nsec: 846888371 }, max_datagram_size: 1350, last_packet_size: Some(69), 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-30T18:25:45.694381781Z TRACE quiche_apps::client] written 69 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.694388654Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.727157340Z TRACE quiche_apps::client] got 748 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.727195231Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=727 pn=2 
[2025-04-30T18:25:45.727211842Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm ACK delay=4 blocks=[3..7] ecn_counts=None
[2025-04-30T18:25:45.727219306Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 packet newly acked 3
[2025-04-30T18:25:45.727224656Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 packet newly acked 4
[2025-04-30T18:25:45.727228293Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 packet newly acked 5
[2025-04-30T18:25:45.727231169Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 packet newly acked 6
[2025-04-30T18:25:45.727234495Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 packet newly acked 7
[2025-04-30T18:25:45.727244093Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[d2, 38, b5, 44, 94, cd, 42, e7, e0, c6, 0f, ec, 14, 73, f1, 65, 72, 5a, c3, 42] reset_token=[99, 4a, 22, 5d, a2, 52, ec, bf, 0b, 07, d6, 29, 08, 4a, f7, e2]
[2025-04-30T18:25:45.727254903Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[11, ec, 54, 1b, db, 61, ae, e8, f8, 05, 81, 5e, ae, 65, c9, 36, d6, 98, e6, 40] reset_token=[ad, b9, 7c, e1, 17, 58, 41, c5, a2, 21, 2b, 19, 4c, ad, 88, f7]
[2025-04-30T18:25:45.727262187Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[1c, c9, f4, b6, bf, 96, 36, 35, 3b, cc, 88, 99, 9a, f1, 76, df, 91, d8, b5, 27] reset_token=[fe, f8, 4e, 55, 62, 30, 1b, 76, b2, 77, b7, cb, ed, 35, 7f, 97]
[2025-04-30T18:25:45.727269310Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[a3, 7e, f5, 22, 82, 34, 3f, 64, 27, 78, 17, ec, cf, 81, cb, 6d, 63, 50, 69, 28] reset_token=[a6, 7f, 3c, 8a, e4, 94, 3d, 0c, f4, dc, b4, e8, cd, 97, 4b, 2e]
[2025-04-30T18:25:45.727276603Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[24, 68, 6a, cd, 97, 74, 3b, fd, f1, f2, 95, aa, 27, 12, fc, c3, bd, 20, 21, 4c] reset_token=[17, d2, d1, 07, 03, 33, 1d, 3e, 60, a0, de, 2f, d2, e9, 95, ae]
[2025-04-30T18:25:45.727283877Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[41, e8, 2a, 8a, fb, ae, ff, 8e, 6b, a3, 9b, 60, 5e, 3c, d5, 02, 2a, 0f, b2, 72] reset_token=[b3, 75, ed, 4c, fe, 88, e3, b8, 40, 6b, 47, e5, 94, 31, 3b, 03]
[2025-04-30T18:25:45.727290930Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[0e, b6, 37, 2a, 52, 4e, df, d2, b5, 27, 4b, 96, 70, 58, 37, bf, c4, fe, b4, b0] reset_token=[80, c2, 1b, 87, 0f, f4, df, f1, 4c, 37, a3, f6, 93, 60, e0, d6]
[2025-04-30T18:25:45.727311308Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm HANDSHAKE_DONE
[2025-04-30T18:25:45.727318021Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 dropped epoch 1 state
[2025-04-30T18:25:45.727322449Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm CRYPTO off=0 len=396
[2025-04-30T18:25:45.727356243Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=3 off=0 len=19 fin=false
[2025-04-30T18:25:45.727390707Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 748 bytes
[2025-04-30T18:25:45.727398291Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.727402569Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=23 pn=3 
[2025-04-30T18:25:45.727408120Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T18:25:45.727414171Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 44 bytes
[2025-04-30T18:25:45.727419501Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.727423047Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=23 pn=4 
[2025-04-30T18:25:45.727427476Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T18:25:45.727432505Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 44 bytes
[2025-04-30T18:25:45.727436974Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.727440139Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.727444477Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 3 is readable
[2025-04-30T18:25:45.727451561Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 3
[2025-04-30T18:25:45.727457893Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 open peer's control stream 3
[2025-04-30T18:25:45.727462050Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 3
[2025-04-30T18:25:45.727465848Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 3
[2025-04-30T18:25:45.727469885Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 16 bytes on stream 3
[2025-04-30T18:25:45.727473472Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(906102319640953149, 5852983442089097)]), additional_settings=Some([(906102319640953149, 5852983442089097)]) stream=3 payload_len=16
[2025-04-30T18:25:45.727483711Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 7 is readable
[2025-04-30T18:25:45.727487769Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 7
[2025-04-30T18:25:45.727492197Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 11 is readable
[2025-04-30T18:25:45.727496695Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 11
[2025-04-30T18:25:45.727509679Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.727515561Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=5 pn=8 
[2025-04-30T18:25:45.727518907Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=10 blocks=[2..4] ecn_counts=None
[2025-04-30T18:25:45.727527372Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=none latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 877527810 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, 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-30T18:25:45.727612261Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.727621980Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.728267156Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.728279850Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1329 pn=5 
[2025-04-30T18:25:45.728289077Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=0 off=0 len=1307 fin=false
[2025-04-30T18:25:45.728297393Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1350 bytes
[2025-04-30T18:25:45.728301260Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.728303725Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.728306440Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.728310788Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728314214Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 7 bytes on stream 0
[2025-04-30T18:25:45.728317280Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728319905Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm UNKNOWN raw_type=3642701411825955661 stream=0 payload_len=0
[2025-04-30T18:25:45.728325556Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728328791Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 7 bytes on stream 0
[2025-04-30T18:25:45.728331727Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728357275Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 18 bytes on stream 0
[2025-04-30T18:25:45.728360030Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm UNKNOWN raw_type=61581459561530349 stream=0 payload_len=18
[2025-04-30T18:25:45.728364318Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728367254Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728370209Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 17 bytes on stream 0
[2025-04-30T18:25:45.728372533Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm HEADERS stream=0 payload_len=17
[2025-04-30T18:25:45.728375629Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T18:25:45.728378204Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-04-30T18:25:45.728385277Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[113, 117, 105, 99, 104, 101]
[2025-04-30T18:25:45.728389475Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[49, 48, 50, 52, 48]
[2025-04-30T18:25:45.728395917Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "quiche"), ("content-length", "10240")] on stream id 0
[2025-04-30T18:25:45.728400476Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.728403511Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728406527Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728409382Z TRACE quiche::h3::stream] dfb2629e0b68476ad0678502d3cb8bf36f40d227 read 1 bytes on stream 0
[2025-04-30T18:25:45.728411657Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm DATA stream=0 wire_payload_len=10240
[2025-04-30T18:25:45.728416355Z DEBUG quiche_apps::common] got 1249 bytes of response data on stream 0
[2025-04-30T18:25:45.728424731Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.728428328Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=5 pn=9 
[2025-04-30T18:25:45.728430953Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=18 blocks=[2..5] ecn_counts=None
[2025-04-30T18:25:45.728437936Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=none latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 7, last_sent_packet: 9, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 877527810 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, 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-30T18:25:45.728484633Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.728492067Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.729359369Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.729371822Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1329 pn=6 
[2025-04-30T18:25:45.729380218Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=0 off=1307 len=1306 fin=false
[2025-04-30T18:25:45.729388574Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1350 bytes
[2025-04-30T18:25:45.729392441Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.729394886Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.729397701Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.729402800Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T18:25:45.729416807Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.729420614Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=5 pn=10 
[2025-04-30T18:25:45.729423279Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=5 blocks=[2..6] ecn_counts=None
[2025-04-30T18:25:45.729430472Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=none latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 7, last_sent_packet: 10, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 877527810 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, 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-30T18:25:45.729474174Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.729481558Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.730462693Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.730475437Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1329 pn=7 
[2025-04-30T18:25:45.730483592Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=0 off=2613 len=1306 fin=false
[2025-04-30T18:25:45.730491948Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1350 bytes
[2025-04-30T18:25:45.730495805Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.730498289Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.730501085Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.730506284Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T18:25:45.730514550Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.730518117Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=5 pn=11 
[2025-04-30T18:25:45.730520731Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=4 blocks=[2..7] ecn_counts=None
[2025-04-30T18:25:45.730528145Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=none latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 7, last_sent_packet: 11, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 877527810 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, 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-30T18:25:45.730577588Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.730585653Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.731572138Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.731585674Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1329 pn=8 
[2025-04-30T18:25:45.731594270Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=0 off=3919 len=1306 fin=false
[2025-04-30T18:25:45.731603968Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1350 bytes
[2025-04-30T18:25:45.731608005Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.731610440Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.731613686Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.731618886Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T18:25:45.731627151Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.731630838Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=5 pn=12 
[2025-04-30T18:25:45.731633473Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=5 blocks=[2..8] ecn_counts=None
[2025-04-30T18:25:45.731640727Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=none latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 7, last_sent_packet: 12, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 877527810 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, 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-30T18:25:45.731691512Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.731701681Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.732673919Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.732686904Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1329 pn=9 
[2025-04-30T18:25:45.732700389Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=0 off=5225 len=1306 fin=false
[2025-04-30T18:25:45.732709055Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1350 bytes
[2025-04-30T18:25:45.732713012Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.732715397Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.732718162Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.732723322Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T18:25:45.732732008Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.732735675Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=5 pn=13 
[2025-04-30T18:25:45.732738380Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=5 blocks=[2..9] ecn_counts=None
[2025-04-30T18:25:45.732745363Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=none latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 7, last_sent_packet: 13, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 877527810 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, 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-30T18:25:45.732791610Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.732799234Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.733752657Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.733760342Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1329 pn=10 
[2025-04-30T18:25:45.733766743Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=0 off=6531 len=1306 fin=false
[2025-04-30T18:25:45.733773656Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1350 bytes
[2025-04-30T18:25:45.733777083Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.733779507Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.733782092Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.733786571Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T18:25:45.733793443Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.733796730Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=5 pn=14 
[2025-04-30T18:25:45.733799244Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=4 blocks=[2..10] ecn_counts=None
[2025-04-30T18:25:45.733810816Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=none latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 7, last_sent_packet: 14, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 877527810 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, 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-30T18:25:45.733847585Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.733854347Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.734862343Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.734868294Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1329 pn=11 
[2025-04-30T18:25:45.734874967Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=0 off=7837 len=1306 fin=false
[2025-04-30T18:25:45.734882160Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1350 bytes
[2025-04-30T18:25:45.734885536Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.734887800Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.734890305Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.734894513Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T18:25:45.734925010Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T18:25:45.734928647Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=5 pn=15 
[2025-04-30T18:25:45.734931121Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm ACK delay=7 blocks=[2..11] ecn_counts=None
[2025-04-30T18:25:45.734937423Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=none latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 7, last_sent_packet: 15, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 877527810 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, 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-30T18:25:45.734999239Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.735011742Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.735838269Z TRACE quiche_apps::client] got 1199 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.735842857Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=1178 pn=12 
[2025-04-30T18:25:45.735849539Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=0 off=9143 len=1155 fin=true
[2025-04-30T18:25:45.735856563Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 1199 bytes
[2025-04-30T18:25:45.735861221Z TRACE quiche_apps::client] got 69 bytes from 193.167.100.100:443 to 0.0.0.0:56474
[2025-04-30T18:25:45.735864047Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx pkt Short dcid=dfb2629e0b68476ad0678502d3cb8bf36f40d227 key_phase=false len=48 pn=13 
[2025-04-30T18:25:45.735867924Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 rx frm STREAM id=15 off=0 len=26 fin=true
[2025-04-30T18:25:45.735874496Z TRACE quiche_apps::client] 0.0.0.0:56474: processed 69 bytes
[2025-04-30T18:25:45.735877863Z TRACE quiche_apps::client] 0.0.0.0:56474: recv() would block
[2025-04-30T18:25:45.735880217Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.735882762Z TRACE quiche::h3] dfb2629e0b68476ad0678502d3cb8bf36f40d227 stream id 0 is readable
[2025-04-30T18:25:45.735888913Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T18:25:45.735891638Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T18:25:45.735894143Z INFO  quiche_apps::common] 1/1 response(s) received in 75.604756ms, closing...
[2025-04-30T18:25:45.735900846Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1312
[2025-04-30T18:25:45.735904292Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx pkt Short dcid=747b303c77f5396506b592183e6e5fc5dcf0156e key_phase=false len=11 pn=16 
[2025-04-30T18:25:45.735906726Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T18:25:45.735915754Z TRACE quiche::recovery::congestion::recovery] dfb2629e0b68476ad0678502d3cb8bf36f40d227 timer=110.725254ms latest_rtt=30.325632ms srtt=32.899819ms min_rtt=30.325632ms rttvar=13.210817ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1991, delivered_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, first_sent_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42753, is_app_limited: true, interval: 33.564535ms, delivered: 1435, prior_delivered: 556, prior_time: Some(Instant { tv_sec: 297, tv_nsec: 843649468 }), send_elapsed: 426.255µs, ack_elapsed: 33.564535ms, rtt: 33.13828ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512920, last_update: Instant { tv_sec: 297, tv_nsec: 885922122 }, next_time: Instant { tv_sec: 297, tv_nsec: 877527810 }, max_datagram_size: 1350, last_packet_size: None, iv: 95.531µ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-30T18:25:45.735952442Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:56474 to 193.167.100.100:443
[2025-04-30T18:25:45.735958654Z TRACE quiche_apps::client] 0.0.0.0:56474 -> 193.167.100.100:443: done writing
[2025-04-30T18:25:45.994257688Z TRACE quiche_apps::client] timed out
[2025-04-30T18:25:45.994290810Z TRACE quiche] dfb2629e0b68476ad0678502d3cb8bf36f40d227 draining timeout expired
[2025-04-30T18:25:45.994340202Z TRACE quiche_apps::client] done reading
[2025-04-30T18:25:45.994345162Z INFO  quiche_apps::client] connection closed, recv=14 sent=17 lost=0 retrans=0 sent_bytes=2567 recv_bytes=11944 lost_bytes=0 [local_addr=0.0.0.0:56474 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=14 sent=17 lost=0 retrans=0 rtt=32.899819ms min_rtt=Some(30.325632ms) rttvar=13.210817ms cwnd=13500 sent_bytes=2567 recv_bytes=11944 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=42753]