[2025-04-30T13:21:19.921110878Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T13:21:19.921397495Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:54338 with scid a78db7b147f70683fbd95be92e1cac4006abbde7
[2025-04-30T13:21:19.921438451Z TRACE quiche::tls] a78db7b147f70683fbd95be92e1cac4006abbde7 write message lvl=Initial len=266
[2025-04-30T13:21:19.921454561Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx pkt Initial version=1 dcid=14cd90062cea1e8b1086d36921fd74ee scid=a78db7b147f70683fbd95be92e1cac4006abbde7 len=270 pn=0 
[2025-04-30T13:21:19.921457888Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm CRYPTO off=0 len=266
[2025-04-30T13:21:19.921473207Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 timer=998.930791ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, first_sent_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, 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: 310, tv_nsec: 289445259 }, next_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, 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-30T13:21:19.921530634Z TRACE quiche_apps::client] written 1200
[2025-04-30T13:21:20.921022120Z TRACE quiche_apps::client] timed out
[2025-04-30T13:21:20.921038110Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 loss detection timeout expired
[2025-04-30T13:21:20.921043931Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 timer=998.359826ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, first_sent_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, 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: 310, tv_nsec: 289445259 }, next_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, 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-30T13:21:20.921063267Z TRACE quiche_apps::client] done reading
[2025-04-30T13:21:20.921092001Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx pkt Initial version=1 dcid=14cd90062cea1e8b1086d36921fd74ee scid=a78db7b147f70683fbd95be92e1cac4006abbde7 len=270 pn=1 
[2025-04-30T13:21:20.921096530Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm CRYPTO off=0 len=266
[2025-04-30T13:21:20.921111498Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 timer=1.997964012s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=666 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, first_sent_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, end_of_app_limited: 1, last_sent_packet: 1, 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: 311, tv_nsec: 289116471 }, next_time: Instant { tv_sec: 311, tv_nsec: 289116471 }, 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-30T13:21:20.921186378Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:54338 to 193.167.100.100:443
[2025-04-30T13:21:20.921194433Z TRACE quiche_apps::client] 0.0.0.0:54338 -> 193.167.100.100:443: done writing
[2025-04-30T13:21:21.429923502Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:54338
[2025-04-30T13:21:21.429949220Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx pkt Initial version=1 dcid=a78db7b147f70683fbd95be92e1cac4006abbde7 scid=4b09c8d944b74967 token= len=116 pn=0 
[2025-04-30T13:21:21.430003211Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T13:21:21.430013440Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 packet newly acked 0
[2025-04-30T13:21:21.430020644Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm CRYPTO off=0 len=90
[2025-04-30T13:21:21.430141010Z TRACE quiche::tls] a78db7b147f70683fbd95be92e1cac4006abbde7 set write secret lvl=Handshake
[2025-04-30T13:21:21.430154184Z TRACE quiche::tls] a78db7b147f70683fbd95be92e1cac4006abbde7 set read secret lvl=Handshake
[2025-04-30T13:21:21.430187947Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx pkt Handshake version=1 dcid=a78db7b147f70683fbd95be92e1cac4006abbde7 scid=4b09c8d944b74967 len=695 pn=0 
[2025-04-30T13:21:21.430195351Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm CRYPTO off=0 len=674
[2025-04-30T13:21:21.430449968Z TRACE quiche::tls] a78db7b147f70683fbd95be92e1cac4006abbde7 write message lvl=Handshake len=36
[2025-04-30T13:21:21.430455008Z TRACE quiche::tls] a78db7b147f70683fbd95be92e1cac4006abbde7 set write secret lvl=OneRTT
[2025-04-30T13:21:21.430463654Z TRACE quiche::tls] a78db7b147f70683fbd95be92e1cac4006abbde7 set read secret lvl=OneRTT
[2025-04-30T13:21:21.430482830Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(14cd90062cea1e8b1086d36921fd74ee), max_idle_timeout: 0, stateless_reset_token: None, max_udp_payload_size: 1472, 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: 100, initial_max_streams_uni: 0, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 2, initial_source_connection_id: Some(4b09c8d944b74967), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T13:21:21.430509761Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx pkt Short dcid=a78db7b147f70683fbd95be92e1cac4006abbde7 key_phase=false len=293 pn=0 
[2025-04-30T13:21:21.430518487Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[5d, 74, 8c, 25, 65, 5d, b5, b1] reset_token=[59, d7, a1, 22, a1, 8c, 80, dc, a6, 32, eb, 63, 24, ef, 26, 09]
[2025-04-30T13:21:21.430533495Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f9, ed, 35, bd, 1b, 15, b5, b7] reset_token=[92, 81, 62, 9f, 34, 9f, 2c, 31, ff, 6b, 00, 6e, 02, 30, be, 59]
[2025-04-30T13:21:21.430539847Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[62, 17, 73, 0e, c4, c0, b5, 31] reset_token=[6f, 37, fa, db, 96, 6c, 15, 92, 80, c7, f5, 80, 05, 70, 33, e6]
[2025-04-30T13:21:21.430545818Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm PADDING len=192
[2025-04-30T13:21:21.430554234Z TRACE quiche_apps::client] 0.0.0.0:54338: processed 1200 bytes
[2025-04-30T13:21:21.430560556Z TRACE quiche_apps::client] 0.0.0.0:54338: recv() would block
[2025-04-30T13:21:21.430564052Z TRACE quiche_apps::client] done reading
[2025-04-30T13:21:21.430574061Z DEBUG quiche_apps::common] sending HTTP request "GET /bejwvcewdt\r\n"
[2025-04-30T13:21:21.430642740Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx pkt Initial version=1 dcid=4b09c8d944b74967 scid=a78db7b147f70683fbd95be92e1cac4006abbde7 len=6 pn=2 
[2025-04-30T13:21:21.430647408Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm ACK delay=86 blocks=[0..0] ecn_counts=None
[2025-04-30T13:21:21.430658289Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 timer=4.016037297s latest_rtt=1.508540113s srtt=1.508540113s min_rtt=1.508540113s rttvar=754.270056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 311, tv_nsec: 797985372 }, first_sent_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508540113s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 310, tv_nsec: 289445259 }), send_elapsed: 0ns, ack_elapsed: 1.508540113s, rtt: 1.508540113s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11186, last_update: Instant { tv_sec: 311, tv_nsec: 798520866 }, next_time: Instant { tv_sec: 311, tv_nsec: 798520906 }, 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-30T13:21:21.430682905Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx pkt Handshake version=1 dcid=4b09c8d944b74967 scid=a78db7b147f70683fbd95be92e1cac4006abbde7 len=45 pn=3 
[2025-04-30T13:21:21.430686441Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm ACK delay=61 blocks=[0..0] ecn_counts=None
[2025-04-30T13:21:21.430689928Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm CRYPTO off=0 len=36
[2025-04-30T13:21:21.430697562Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 timer=4.015998395s latest_rtt=1.508540113s srtt=1.508540113s min_rtt=1.508540113s rttvar=754.270056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=432 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 311, tv_nsec: 797985372 }, first_sent_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508540113s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 310, tv_nsec: 289445259 }), send_elapsed: 0ns, ack_elapsed: 1.508540113s, rtt: 1.508540113s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11186, last_update: Instant { tv_sec: 311, tv_nsec: 798673992 }, next_time: Instant { tv_sec: 311, tv_nsec: 798520906 }, max_datagram_size: 1350, last_packet_size: None, iv: 8.850349ms, 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-30T13:21:21.430718181Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 dropped epoch 0 state
[2025-04-30T13:21:21.430724332Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13401 out_len=1190 left=1159
[2025-04-30T13:21:21.430739251Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx pkt Short dcid=4b09c8d944b74967 key_phase=false len=1164 pn=4 
[2025-04-30T13:21:21.430742587Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm ACK delay=26 blocks=[0..0] ecn_counts=None
[2025-04-30T13:21:21.430746073Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[b8, a1, 31, 5c, 27, d5, a4, d6, 48, 39, f1, 4c, a0, 91, 41, d6, b9, 0e, e1, be] reset_token=[dc, 28, 5d, 2c, 4a, ef, bc, 1f, 63, e4, 07, 01, 08, 30, c4, db]
[2025-04-30T13:21:21.430753738Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T13:21:21.430757114Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm PADDING len=1097
[2025-04-30T13:21:21.430765400Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 timer=4.525488029s latest_rtt=1.508540113s srtt=1.508540113s min_rtt=1.508540113s rttvar=754.270056ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 311, tv_nsec: 797985372 }, first_sent_time: Instant { tv_sec: 310, tv_nsec: 289445259 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.508540113s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 310, tv_nsec: 289445259 }), send_elapsed: 0ns, ack_elapsed: 1.508540113s, rtt: 1.508540113s } } pacer=Pacer { enabled: true, capacity: 13500, used: 1190, rate: 11186, last_update: Instant { tv_sec: 311, tv_nsec: 798673992 }, next_time: Instant { tv_sec: 311, tv_nsec: 807524341 }, max_datagram_size: 1350, last_packet_size: Some(1190), 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-30T13:21:21.430819190Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:54338 to 193.167.100.100:443
[2025-04-30T13:21:21.430825753Z TRACE quiche_apps::client] 0.0.0.0:54338 -> 193.167.100.100:443: done writing
[2025-04-30T13:21:22.422850681Z TRACE quiche_apps::client] got 60 bytes from 193.167.100.100:443 to 0.0.0.0:54338
[2025-04-30T13:21:22.422871039Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 dropped invalid packet
[2025-04-30T13:21:22.422875618Z TRACE quiche_apps::client] 0.0.0.0:54338: processed 60 bytes
[2025-04-30T13:21:22.422880918Z TRACE quiche_apps::client] 0.0.0.0:54338: recv() would block
[2025-04-30T13:21:22.422884474Z TRACE quiche_apps::client] done reading
[2025-04-30T13:21:22.422891758Z TRACE quiche_apps::client] 0.0.0.0:54338 -> 193.167.100.100:443: done writing
[2025-04-30T13:21:22.429986673Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:54338
[2025-04-30T13:21:22.430001129Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 dropped invalid packet
[2025-04-30T13:21:22.430004746Z TRACE quiche_apps::client] 0.0.0.0:54338: processed 1200 bytes
[2025-04-30T13:21:22.430008554Z TRACE quiche_apps::client] 0.0.0.0:54338: recv() would block
[2025-04-30T13:21:22.430011118Z TRACE quiche_apps::client] done reading
[2025-04-30T13:21:22.430022269Z TRACE quiche_apps::client] 0.0.0.0:54338 -> 193.167.100.100:443: done writing
[2025-04-30T13:21:22.932766497Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:54338
[2025-04-30T13:21:22.932779181Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx pkt Short dcid=a78db7b147f70683fbd95be92e1cac4006abbde7 key_phase=false len=23 pn=1 
[2025-04-30T13:21:22.932789120Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm ACK delay=0 blocks=[4..4] ecn_counts=None
[2025-04-30T13:21:22.932793518Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 packet newly acked 4
[2025-04-30T13:21:22.932798097Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm HANDSHAKE_DONE
[2025-04-30T13:21:22.932803286Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 dropped epoch 1 state
[2025-04-30T13:21:22.932817673Z TRACE quiche_apps::client] 0.0.0.0:54338: processed 44 bytes
[2025-04-30T13:21:22.932821601Z TRACE quiche_apps::client] 0.0.0.0:54338: recv() would block
[2025-04-30T13:21:22.932824055Z TRACE quiche_apps::client] done reading
[2025-04-30T13:21:22.932832271Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T13:21:22.932836298Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx pkt Short dcid=4b09c8d944b74967 key_phase=false len=5 pn=5 
[2025-04-30T13:21:22.932838993Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm ACK delay=6 blocks=[1..1] ecn_counts=None
[2025-04-30T13:21:22.932845986Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 timer=none latest_rtt=1.493293367s srtt=1.506634268s min_rtt=1.493293367s rttvar=569.514228ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1523, delivered_time: Instant { tv_sec: 313, tv_nsec: 300868152 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 300868152 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 4, rate_sample: RateSample { delivery_rate: 783, is_app_limited: true, interval: 1.518079082s, delivered: 1190, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 311, tv_nsec: 797985372 }), send_elapsed: 1.518079082s, ack_elapsed: 1.502832336s, rtt: 1.493293367s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11200, last_update: Instant { tv_sec: 313, tv_nsec: 300868152 }, next_time: Instant { tv_sec: 313, tv_nsec: 300868152 }, 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-30T13:21:22.932889408Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54338 to 193.167.100.100:443
[2025-04-30T13:21:22.932894067Z TRACE quiche_apps::client] 0.0.0.0:54338 -> 193.167.100.100:443: done writing
[2025-04-30T13:21:22.933727839Z TRACE quiche_apps::client] got 1066 bytes from 193.167.100.100:443 to 0.0.0.0:54338
[2025-04-30T13:21:22.933739981Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx pkt Short dcid=a78db7b147f70683fbd95be92e1cac4006abbde7 key_phase=false len=1045 pn=2 
[2025-04-30T13:21:22.933748508Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 rx frm STREAM id=0 off=0 len=1024 fin=true
[2025-04-30T13:21:22.933756953Z TRACE quiche_apps::client] 0.0.0.0:54338: processed 1066 bytes
[2025-04-30T13:21:22.933760851Z TRACE quiche_apps::client] 0.0.0.0:54338: recv() would block
[2025-04-30T13:21:22.933763376Z TRACE quiche_apps::client] done reading
[2025-04-30T13:21:22.933770158Z TRACE quiche_apps::common] received 1024 bytes
[2025-04-30T13:21:22.933773003Z TRACE quiche_apps::common] stream 0 has 1024 bytes (fin? true)
[2025-04-30T13:21:22.933775588Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T13:21:22.933784896Z INFO  quiche_apps::common] 1/1 response(s) received in 3.012250415s, closing...
[2025-04-30T13:21:22.933792370Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T13:21:22.933795966Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx pkt Short dcid=4b09c8d944b74967 key_phase=false len=10 pn=6 
[2025-04-30T13:21:22.933798531Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T13:21:22.933806296Z TRACE quiche::recovery::congestion::recovery] a78db7b147f70683fbd95be92e1cac4006abbde7 timer=3.809674048s latest_rtt=1.493293367s srtt=1.506634268s min_rtt=1.493293367s rttvar=569.514228ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=36 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1523, delivered_time: Instant { tv_sec: 313, tv_nsec: 300868152 }, first_sent_time: Instant { tv_sec: 313, tv_nsec: 300868152 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 4, rate_sample: RateSample { delivery_rate: 783, is_app_limited: true, interval: 1.518079082s, delivered: 1190, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 311, tv_nsec: 797985372 }), send_elapsed: 1.518079082s, ack_elapsed: 1.502832336s, rtt: 1.493293367s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11200, last_update: Instant { tv_sec: 313, tv_nsec: 301830065 }, next_time: Instant { tv_sec: 313, tv_nsec: 300868152 }, max_datagram_size: 1350, last_packet_size: None, iv: 3.214286ms, 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-30T13:21:22.933846902Z TRACE quiche_apps::client] written 36 bytes from 0.0.0.0:54338 to 193.167.100.100:443
[2025-04-30T13:21:22.933851230Z TRACE quiche_apps::client] 0.0.0.0:54338 -> 193.167.100.100:443: done writing
[2025-04-30T13:21:34.300278232Z TRACE quiche_apps::client] timed out
[2025-04-30T13:21:34.300306274Z TRACE quiche] a78db7b147f70683fbd95be92e1cac4006abbde7 draining timeout expired
[2025-04-30T13:21:34.300364162Z TRACE quiche_apps::client] done reading
[2025-04-30T13:21:34.300371146Z INFO  quiche_apps::client] connection closed, recv=5 sent=7 lost=0 retrans=1 sent_bytes=2083 recv_bytes=2310 lost_bytes=0 [local_addr=0.0.0.0:54338 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=5 sent=7 lost=0 retrans=1 rtt=1.506634268s min_rtt=Some(1.493293367s) rttvar=569.514228ms cwnd=13500 sent_bytes=2083 recv_bytes=2310 lost_bytes=0 stream_retrans_bytes=266 pmtu=1350 delivery_rate=783]