[2025-04-30T19:24:53.380789583Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T19:24:53.381082551Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:44302 with scid def114d4c0c6f4a10fc0e84c4ed974617bab71c3
[2025-04-30T19:24:53.381132575Z TRACE quiche::tls] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 write message lvl=Initial len=393
[2025-04-30T19:24:53.381151320Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Initial version=1 dcid=7f0079a6f06a8823f8704b6b2a70833c scid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 len=397 pn=0 
[2025-04-30T19:24:53.381154626Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm CRYPTO off=0 len=393
[2025-04-30T19:24:53.381168082Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=998.921062ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=460 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 286, tv_nsec: 383365028 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 383365028 }, 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: 286, tv_nsec: 383365028 }, next_time: Instant { tv_sec: 286, tv_nsec: 383365028 }, 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-30T19:24:53.381266386Z TRACE quiche_apps::client] written 1200
[2025-04-30T19:24:53.416245689Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.416270806Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Initial version=1 dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 scid=5b2c12c35509834b token= len=124 pn=0 
[2025-04-30T19:24:53.416286285Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm ACK delay=254 blocks=[0..0] ecn_counts=None
[2025-04-30T19:24:53.416292868Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 packet newly acked 0
[2025-04-30T19:24:53.416299580Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm CRYPTO off=0 len=96
[2025-04-30T19:24:53.416389538Z TRACE quiche::tls] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 set write secret lvl=Handshake
[2025-04-30T19:24:53.416401050Z TRACE quiche::tls] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 set read secret lvl=Handshake
[2025-04-30T19:24:53.416429153Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Handshake version=1 dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 scid=5b2c12c35509834b len=206 pn=1 
[2025-04-30T19:24:53.416435374Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm CRYPTO off=0 len=184
[2025-04-30T19:24:53.416471382Z TRACE quiche::tls] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 write message lvl=Handshake len=52
[2025-04-30T19:24:53.416475069Z TRACE quiche::tls] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 set write secret lvl=OneRTT
[2025-04-30T19:24:53.416484156Z TRACE quiche::tls] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 set read secret lvl=OneRTT
[2025-04-30T19:24:53.416504874Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 connection established: proto=Ok("h3") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=true TransportParams { original_destination_connection_id: Some(7f0079a6f06a8823f8704b6b2a70833c), max_idle_timeout: 60000, stateless_reset_token: Some(49038879227056420080479849878155697279), max_udp_payload_size: 65527, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 1048576, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 1048576, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(5b2c12c35509834b), retry_source_connection_id: None, max_datagram_frame_size: Some(65536), unknown_params: None }
[2025-04-30T19:24:53.416542204Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=774 pn=2 
[2025-04-30T19:24:53.416548777Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm CRYPTO off=0 len=89
[2025-04-30T19:24:53.416563745Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=3 off=0 len=19 fin=false
[2025-04-30T19:24:53.416569375Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T19:24:53.416573313Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T19:24:53.416579915Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm PADDING len=630
[2025-04-30T19:24:53.416586948Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.416596225Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.416600012Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.416619208Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm SETTINGS stream=2 len=18
[2025-04-30T19:24:53.416634557Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 open GREASE stream 14
[2025-04-30T19:24:53.416646079Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm GREASE stream=0 len=0
[2025-04-30T19:24:53.416653352Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm GREASE stream=0 len=18
[2025-04-30T19:24:53.416662339Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm HEADERS stream=0 len=30 fin=true
[2025-04-30T19:24:53.416670224Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /sruckuycav", "user-agent: quiche"]
[2025-04-30T19:24:53.416776883Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 3 is readable
[2025-04-30T19:24:53.416788095Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 3
[2025-04-30T19:24:53.416793365Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 open peer's control stream 3
[2025-04-30T19:24:53.416797592Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 3
[2025-04-30T19:24:53.416801540Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 3
[2025-04-30T19:24:53.416805788Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 16 bytes on stream 3
[2025-04-30T19:24:53.416810186Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm SETTINGS max_field_section=None, qpack_max_table=Some(4096), qpack_blocked=Some(16) raw=Some([(1, 4096), (7, 16), (8, 1), (33, 1), (51, 1), (727725890, 1)]), additional_settings=Some([(33, 1), (727725890, 1)]) stream=3 payload_len=16
[2025-04-30T19:24:53.416821247Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 7 is readable
[2025-04-30T19:24:53.416825595Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 7
[2025-04-30T19:24:53.416829883Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 11 is readable
[2025-04-30T19:24:53.416834291Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 11
[2025-04-30T19:24:53.416863886Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Initial version=1 dcid=5b2c12c35509834b scid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 len=6 pn=1 
[2025-04-30T19:24:53.416867483Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=74 blocks=[0..0] ecn_counts=None
[2025-04-30T19:24:53.416906787Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=104.883768ms latest_rtt=35.175391ms srtt=35.175391ms min_rtt=35.175391ms rttvar=17.587695ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 460, delivered_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 13077, is_app_limited: true, interval: 35.175391ms, delivered: 460, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 383365028 }), send_elapsed: 0ns, ack_elapsed: 35.175391ms, rtt: 35.175391ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 479738, last_update: Instant { tv_sec: 286, tv_nsec: 418777553 }, next_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, 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-30T19:24:53.416938326Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Handshake version=1 dcid=5b2c12c35509834b scid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 len=61 pn=2 
[2025-04-30T19:24:53.416941883Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=63 blocks=[1..1] ecn_counts=None
[2025-04-30T19:24:53.416945158Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm CRYPTO off=0 len=52
[2025-04-30T19:24:53.416952612Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=105.427056ms latest_rtt=35.175391ms srtt=35.175391ms min_rtt=35.175391ms rttvar=17.587695ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=115 app_limited=true congestion_recovery_start_time=None Rate { delivered: 460, delivered_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 13077, is_app_limited: true, interval: 35.175391ms, delivered: 460, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 383365028 }), send_elapsed: 0ns, ack_elapsed: 35.175391ms, rtt: 35.175391ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 479738, last_update: Instant { tv_sec: 286, tv_nsec: 419129081 }, next_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, max_datagram_size: 1350, last_packet_size: None, iv: 239.714µ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-30T19:24:53.416967651Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 dropped epoch 0 state
[2025-04-30T19:24:53.416972870Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13385 out_len=1174 left=1143
[2025-04-30T19:24:53.416987227Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=1148 pn=3 
[2025-04-30T19:24:53.416990674Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=53 blocks=[2..2] ecn_counts=None
[2025-04-30T19:24:53.416994080Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ca, 87, 75, 6f, 6c, cc, 43, ba, 87, ec, 2b, f0, 3f, 86, 1d, 02, e1, 5b, d4, 8c] reset_token=[9e, 9b, c3, a0, 7d, 66, 88, 5e, 21, 2f, cd, 58, b0, df, d1, 95]
[2025-04-30T19:24:53.417002506Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[3d, 19, c1, ad, 59, 85, 28, b5, 75, 72, f4, 12, 86, 28, e9, c9, 16, 24, 8d, d6] reset_token=[be, f6, 5f, 5e, ca, 5d, db, 35, 16, 2b, b5, 17, 26, 48, 84, e2]
[2025-04-30T19:24:53.417013667Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[df, 5d, a9, 24, e6, e8, fa, 76, 97, 58, ec, 97, 2f, 03, 77, bd, cd, d5, a2, c1] reset_token=[97, ab, e3, f3, 83, 79, 35, e3, 0a, c5, c4, e2, c7, 31, 40, d4]
[2025-04-30T19:24:53.417020951Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[b5, cc, 17, d4, 9f, 65, b3, 34, 1a, 05, 65, 6f, 27, 83, 4f, 49, 0a, c4, 13, 75] reset_token=[27, 0d, 52, 0e, af, 91, 12, ba, e4, 5a, b0, 24, 93, 05, 37, 2b]
[2025-04-30T19:24:53.417028425Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[6e, 55, 6b, b7, 9c, bb, 84, ba, aa, ea, e8, b0, 67, 1c, ca, ff, f2, da, 2b, 27] reset_token=[f5, 71, 17, 48, b1, 7b, dd, 53, 8f, b1, 89, 41, d2, 4d, a4, 0d]
[2025-04-30T19:24:53.417035558Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[e8, 7c, a2, d0, df, ba, d0, 7b, f3, bd, 03, 0f, 41, 4a, c2, 9e, 58, 81, b1, f9] reset_token=[62, f3, 11, 70, 2d, 12, e0, 62, bf, 2d, 5a, 5e, f8, 49, 82, db]
[2025-04-30T19:24:53.417042621Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[31, ac, 0d, b3, 10, 28, be, 20, 3e, 4f, d0, 9e, 0e, 21, c2, 67, 07, 1e, da, b6] reset_token=[b3, 41, 23, a2, 12, b1, 7f, 80, 68, b5, 27, 3f, b8, f7, 98, 0b]
[2025-04-30T19:24:53.417049814Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T19:24:53.417053111Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm PADDING len=839
[2025-04-30T19:24:53.417065153Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=105.314505ms latest_rtt=35.175391ms srtt=35.175391ms min_rtt=35.175391ms rttvar=17.587695ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 460, delivered_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 13077, is_app_limited: true, interval: 35.175391ms, delivered: 460, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 383365028 }), send_elapsed: 0ns, ack_elapsed: 35.175391ms, rtt: 35.175391ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1174, rate: 479738, last_update: Instant { tv_sec: 286, tv_nsec: 419129081 }, next_time: Instant { tv_sec: 286, tv_nsec: 419368795 }, max_datagram_size: 1350, last_packet_size: Some(1174), 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-30T19:24:53.417157546Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.417230573Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12211 out_len=1350 left=1324
[2025-04-30T19:24:53.417236494Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=6 pn=4 
[2025-04-30T19:24:53.417240201Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T19:24:53.417248476Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=105.130961ms latest_rtt=35.175391ms srtt=35.175391ms min_rtt=35.175391ms rttvar=17.587695ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 460, delivered_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 13077, is_app_limited: true, interval: 35.175391ms, delivered: 460, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 383365028 }), send_elapsed: 0ns, ack_elapsed: 35.175391ms, rtt: 35.175391ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 479738, last_update: Instant { tv_sec: 286, tv_nsec: 419502039 }, next_time: Instant { tv_sec: 286, tv_nsec: 419368795 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.513872ms, 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-30T19:24:53.417314530Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.417327554Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12179 out_len=1350 left=1324
[2025-04-30T19:24:53.417334447Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=6 pn=5 
[2025-04-30T19:24:53.417339988Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T19:24:53.417351509Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=105.027608ms latest_rtt=35.175391ms srtt=35.175391ms min_rtt=35.175391ms rttvar=17.587695ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1353 app_limited=true congestion_recovery_start_time=None Rate { delivered: 460, delivered_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 13077, is_app_limited: true, interval: 35.175391ms, delivered: 460, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 383365028 }), send_elapsed: 0ns, ack_elapsed: 35.175391ms, rtt: 35.175391ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 479738, last_update: Instant { tv_sec: 286, tv_nsec: 419502039 }, next_time: Instant { tv_sec: 286, tv_nsec: 422112141 }, max_datagram_size: 1350, last_packet_size: Some(32), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T19:24:53.417415619Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.417424887Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12147 out_len=1350 left=1324
[2025-04-30T19:24:53.417430477Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=73 pn=6 
[2025-04-30T19:24:53.417434184Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T19:24:53.417443271Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=104.936167ms latest_rtt=35.175391ms srtt=35.175391ms min_rtt=35.175391ms rttvar=17.587695ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1452 app_limited=true congestion_recovery_start_time=None Rate { delivered: 460, delivered_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 13077, is_app_limited: true, interval: 35.175391ms, delivered: 460, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 383365028 }), send_elapsed: 0ns, ack_elapsed: 35.175391ms, rtt: 35.175391ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 479738, last_update: Instant { tv_sec: 286, tv_nsec: 419696704 }, next_time: Instant { tv_sec: 286, tv_nsec: 422112141 }, max_datagram_size: 1350, last_packet_size: None, iv: 273.066µ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-30T19:24:53.417479829Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.417486282Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12048 out_len=1350 left=1324
[2025-04-30T19:24:53.417491722Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=31 pn=7 
[2025-04-30T19:24:53.417495769Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T19:24:53.417505768Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=104.8737ms latest_rtt=35.175391ms srtt=35.175391ms min_rtt=35.175391ms rttvar=17.587695ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1509 app_limited=true congestion_recovery_start_time=None Rate { delivered: 460, delivered_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 418777583 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 13077, is_app_limited: true, interval: 35.175391ms, delivered: 460, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 383365028 }), send_elapsed: 0ns, ack_elapsed: 35.175391ms, rtt: 35.175391ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 57, rate: 479738, last_update: Instant { tv_sec: 286, tv_nsec: 419696704 }, next_time: Instant { tv_sec: 286, tv_nsec: 422385207 }, max_datagram_size: 1350, last_packet_size: Some(57), 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-30T19:24:53.417549921Z TRACE quiche_apps::client] written 57 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.417554980Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.450466694Z TRACE quiche_apps::client] got 242 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.450484176Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=221 pn=3 
[2025-04-30T19:24:53.450493564Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm ACK delay=169 blocks=[3..3] ecn_counts=None
[2025-04-30T19:24:53.450498443Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 packet newly acked 3
[2025-04-30T19:24:53.450503553Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm HANDSHAKE_DONE
[2025-04-30T19:24:53.450508753Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 dropped epoch 1 state
[2025-04-30T19:24:53.450513582Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[eb, 50, b2, 19, 62, 6e, b4, 5b] reset_token=[c4, 8a, 1f, 74, 12, e4, 5e, 2a, 80, 94, 83, f6, 43, 2e, a7, f9]
[2025-04-30T19:24:53.450527738Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[79, 34, 19, 3f, 3a, d7, c6, 42] reset_token=[e1, fc, 25, 77, b5, 6f, 82, d5, db, e0, a5, a0, f6, ec, 53, 0d]
[2025-04-30T19:24:53.450534070Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[8c, 7e, f7, a7, 3b, de, e0, 5c] reset_token=[46, 55, d3, fa, 33, c5, 61, 67, bb, 14, ed, 3c, 4f, dd, 01, 6c]
[2025-04-30T19:24:53.450539630Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[0f, 25, 77, cd, 9e, 03, 38, 71] reset_token=[aa, c5, 69, ce, 72, d2, 3c, 3f, 7e, ce, 0f, 4e, 9a, 22, 07, 15]
[2025-04-30T19:24:53.450545481Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[3c, 23, 7a, 69, 4c, da, 13, f9] reset_token=[29, 37, f6, b1, 57, f7, 55, 8c, e9, 59, 92, 95, 4f, 20, b6, bf]
[2025-04-30T19:24:53.450550981Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[2f, 53, 88, f2, 00, a2, c0, 1e] reset_token=[58, 5a, f6, ce, 15, 2c, b4, 21, 07, 42, 9b, 4e, fa, 6b, bc, 98]
[2025-04-30T19:24:53.450556532Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[60, 9b, e2, 7d, d7, 6f, c3, f0] reset_token=[f4, a3, 6e, ff, c1, 49, 6f, cf, ec, f4, 4b, d8, 24, 82, 3c, 8f]
[2025-04-30T19:24:53.450577090Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 242 bytes
[2025-04-30T19:24:53.450581248Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.450583753Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.450592820Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13280 out_len=1350 left=1319
[2025-04-30T19:24:53.450597909Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=8 
[2025-04-30T19:24:53.450600454Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=13 blocks=[3..3] ecn_counts=None
[2025-04-30T19:24:53.450607537Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=81.378838ms latest_rtt=33.387509ms srtt=34.951905ms min_rtt=33.387509ms rttvar=13.637741ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=220 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1634, delivered_time: Instant { tv_sec: 286, tv_nsec: 452756304 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 419368795 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 3, rate_sample: RateSample { delivery_rate: 34551, is_app_limited: true, interval: 33.978721ms, delivered: 1174, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482806, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.450648764Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.450653283Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.451559760Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.451570309Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=23 pn=4 
[2025-04-30T19:24:53.451581651Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm ACK delay=33 blocks=[3..7] ecn_counts=None
[2025-04-30T19:24:53.451585268Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 packet newly acked 4
[2025-04-30T19:24:53.451587772Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 packet newly acked 5
[2025-04-30T19:24:53.451590106Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 packet newly acked 6
[2025-04-30T19:24:53.451592331Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 packet newly acked 7
[2025-04-30T19:24:53.451606738Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 44 bytes
[2025-04-30T19:24:53.451610595Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.451613060Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.451617949Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.452216339Z TRACE quiche_apps::client] got 163 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.452226377Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=142 pn=5 
[2025-04-30T19:24:53.452232529Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=0 len=120 fin=false
[2025-04-30T19:24:53.452240163Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 163 bytes
[2025-04-30T19:24:53.452243770Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.452246225Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.452248980Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.452253719Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 0
[2025-04-30T19:24:53.452257395Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 0
[2025-04-30T19:24:53.452260581Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 0
[2025-04-30T19:24:53.452264970Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 117 bytes on stream 0
[2025-04-30T19:24:53.452268026Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm HEADERS stream=0 payload_len=117
[2025-04-30T19:24:53.452271552Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T19:24:53.452274277Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-04-30T19:24:53.452278726Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[97, 105, 111, 113, 117, 105, 99, 47, 49, 46, 50, 46, 48]
[2025-04-30T19:24:53.452285799Z TRACE quiche::h3::qpack::decoder] Literal name_idx=6 static=true value=[87, 101, 100, 44, 32, 51, 48, 32, 65, 112, 114, 32, 50, 48, 50, 53, 32, 49, 57, 58, 50, 52, 58, 53, 51, 32, 71, 77, 84]
[2025-04-30T19:24:53.452291900Z TRACE quiche::h3::qpack::decoder] Literal name_idx=44 static=true value=[116, 101, 120, 116, 47, 112, 108, 97, 105, 110, 59, 32, 99, 104, 97, 114, 115, 101, 116, 61, 117, 116, 102, 45, 56]
[2025-04-30T19:24:53.452296248Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[49, 48, 50, 52, 48]
[2025-04-30T19:24:53.452299665Z TRACE quiche::h3::qpack::decoder] Literal name_idx=10 static=true value=[87, 101, 100, 44, 32, 51, 48, 32, 65, 112, 114, 32, 50, 48, 50, 53, 32, 49, 57, 58, 50, 52, 58, 53, 49, 32, 71, 77, 84]
[2025-04-30T19:24:53.452304904Z TRACE quiche::h3::qpack::decoder] Literal name_idx=7 static=true value=[34, 49, 50, 51, 54, 55, 102, 102, 49, 98, 102, 98, 97, 101, 57, 49, 49, 55, 56, 50, 53, 101, 102, 102, 101, 53, 55, 97, 54, 99, 102, 57, 50, 34]
[2025-04-30T19:24:53.452329150Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "aioquic/1.2.0"), ("date", "Wed, 30 Apr 2025 19:24:53 GMT"), ("content-type", "text/plain; charset=utf-8"), ("content-length", "10240"), ("last-modified", "Wed, 30 Apr 2025 19:24:51 GMT"), ("etag", "\"12367ff1bfbae9117825effe57a6cf92\"")] on stream id 0
[2025-04-30T19:24:53.452344048Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.452347615Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=9 
[2025-04-30T19:24:53.452350219Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=14 blocks=[3..5] ecn_counts=None
[2025-04-30T19:24:53.452356942Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.452398189Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.452402808Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.453537011Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.453547170Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1179 pn=6 
[2025-04-30T19:24:53.453554974Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=120 len=1155 fin=false
[2025-04-30T19:24:53.453562719Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.453566366Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.453568830Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.453571445Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.453575483Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 0
[2025-04-30T19:24:53.453578809Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 0
[2025-04-30T19:24:53.453581975Z TRACE quiche::h3::stream] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 read 1 bytes on stream 0
[2025-04-30T19:24:53.453584459Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm DATA stream=0 wire_payload_len=10240
[2025-04-30T19:24:53.453589930Z DEBUG quiche_apps::common] got 1152 bytes of response data on stream 0
[2025-04-30T19:24:53.453597203Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.453600600Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=10 
[2025-04-30T19:24:53.453603205Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=6 blocks=[3..6] ecn_counts=None
[2025-04-30T19:24:53.453609617Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 10, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.453651736Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.453656064Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.456972381Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.456984604Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1179 pn=7 
[2025-04-30T19:24:53.456992659Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=1275 len=1155 fin=false
[2025-04-30T19:24:53.457001676Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.457005984Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.457009009Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.457012166Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.457017946Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:24:53.457026352Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.457030219Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=11 
[2025-04-30T19:24:53.457033325Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=5 blocks=[3..7] ecn_counts=None
[2025-04-30T19:24:53.457040859Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 11, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.457088809Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.457093858Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.457947096Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.457957194Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1179 pn=8 
[2025-04-30T19:24:53.457964147Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=2430 len=1155 fin=false
[2025-04-30T19:24:53.457971531Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.457975228Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.457977753Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.457980348Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.457984916Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:24:53.457991980Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.457995346Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=12 
[2025-04-30T19:24:53.457997900Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=4 blocks=[3..8] ecn_counts=None
[2025-04-30T19:24:53.458004202Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 12, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.458039839Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.458044037Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.460240108Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.460250146Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1179 pn=9 
[2025-04-30T19:24:53.460257079Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=3585 len=1155 fin=false
[2025-04-30T19:24:53.460264493Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.460268070Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.460270605Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.460273279Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.460283148Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:24:53.460290442Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.460293718Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=13 
[2025-04-30T19:24:53.460296222Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=5 blocks=[3..9] ecn_counts=None
[2025-04-30T19:24:53.460302574Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 13, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.460338802Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.460342780Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.461224771Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.461234950Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1179 pn=10 
[2025-04-30T19:24:53.461242043Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=4740 len=1155 fin=false
[2025-04-30T19:24:53.461249417Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.461252983Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.461255458Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.461258103Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.461262601Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:24:53.461269344Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.461272580Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=14 
[2025-04-30T19:24:53.461275045Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=4 blocks=[3..10] ecn_counts=None
[2025-04-30T19:24:53.461281186Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 14, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.461322103Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.461326341Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.463512252Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.463523834Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1179 pn=11 
[2025-04-30T19:24:53.463530967Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=5895 len=1155 fin=false
[2025-04-30T19:24:53.463538531Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.463542248Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.463544723Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.463547348Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.463551947Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:24:53.463558929Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.463562306Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=15 
[2025-04-30T19:24:53.463564800Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=4 blocks=[3..11] ecn_counts=None
[2025-04-30T19:24:53.463571143Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 15, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.463607540Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.463611538Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.464491165Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.464501915Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1179 pn=12 
[2025-04-30T19:24:53.464514017Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=7050 len=1155 fin=false
[2025-04-30T19:24:53.464521562Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.464525228Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.464527763Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.464530328Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.464534886Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:24:53.464541719Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.464544985Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=16 
[2025-04-30T19:24:53.464547470Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=5 blocks=[3..12] ecn_counts=None
[2025-04-30T19:24:53.464554373Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 16, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.464590450Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.464594488Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.466836515Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.466847295Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1179 pn=13 
[2025-04-30T19:24:53.466853877Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=8205 len=1155 fin=false
[2025-04-30T19:24:53.466861341Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1200 bytes
[2025-04-30T19:24:53.466864958Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.466867442Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.466870067Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.466874606Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T19:24:53.466899102Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T19:24:53.466902748Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=5 pn=17 
[2025-04-30T19:24:53.466905203Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm ACK delay=6 blocks=[3..13] ecn_counts=None
[2025-04-30T19:24:53.466914461Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=none latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 8, last_sent_packet: 17, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 452864226 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, 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-30T19:24:53.466951079Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.466955137Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.467700680Z TRACE quiche_apps::client] got 1048 bytes from 193.167.100.100:443 to 0.0.0.0:44302
[2025-04-30T19:24:53.467709336Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx pkt Short dcid=def114d4c0c6f4a10fc0e84c4ed974617bab71c3 key_phase=false len=1027 pn=14 
[2025-04-30T19:24:53.467716139Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 rx frm STREAM id=0 off=9360 len=1003 fin=true
[2025-04-30T19:24:53.467724525Z TRACE quiche_apps::client] 0.0.0.0:44302: processed 1048 bytes
[2025-04-30T19:24:53.467754911Z TRACE quiche_apps::client] 0.0.0.0:44302: recv() would block
[2025-04-30T19:24:53.467757727Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.467760462Z TRACE quiche::h3] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 stream id 0 is readable
[2025-04-30T19:24:53.467766824Z DEBUG quiche_apps::common] got 1003 bytes of response data on stream 0
[2025-04-30T19:24:53.467769569Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T19:24:53.467772294Z INFO  quiche_apps::common] 1/1 response(s) received in 86.501671ms, closing...
[2025-04-30T19:24:53.467779387Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T19:24:53.467783144Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx pkt Short dcid=5b2c12c35509834b key_phase=false len=11 pn=18 
[2025-04-30T19:24:53.467785639Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T19:24:53.467792913Z TRACE quiche::recovery::congestion::recovery] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 timer=103.904896ms latest_rtt=31.458963ms srtt=34.515286ms min_rtt=31.458963ms rttvar=11.10154ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1854, delivered_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, first_sent_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 7, rate_sample: RateSample { delivery_rate: 41025, is_app_limited: true, interval: 33.978721ms, delivered: 1394, prior_delivered: 460, prior_time: Some(Instant { tv_sec: 286, tv_nsec: 418777583 }), send_elapsed: 591.212µs, ack_elapsed: 33.978721ms, rtt: 33.387509ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488913, last_update: Instant { tv_sec: 286, tv_nsec: 470051946 }, next_time: Instant { tv_sec: 286, tv_nsec: 452864226 }, max_datagram_size: 1350, last_packet_size: None, iv: 75.678µ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-30T19:24:53.467836324Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:44302 to 193.167.100.100:443
[2025-04-30T19:24:53.467840351Z TRACE quiche_apps::client] 0.0.0.0:44302 -> 193.167.100.100:443: done writing
[2025-04-30T19:24:53.705123061Z TRACE quiche_apps::client] timed out
[2025-04-30T19:24:53.705142567Z TRACE quiche] def114d4c0c6f4a10fc0e84c4ed974617bab71c3 draining timeout expired
[2025-04-30T19:24:53.705179046Z TRACE quiche_apps::client] done reading
[2025-04-30T19:24:53.705183023Z INFO  quiche_apps::client] connection closed, recv=15 sent=19 lost=0 retrans=0 sent_bytes=2377 recv_bytes=12297 lost_bytes=0 [local_addr=0.0.0.0:44302 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=15 sent=19 lost=0 retrans=0 rtt=34.515286ms min_rtt=Some(31.458963ms) rttvar=11.10154ms cwnd=13500 sent_bytes=2377 recv_bytes=12297 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=41025]