[2025-04-30T08:41:38.147208493Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T08:41:38.147523842Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:40853 with scid 4484685b5b9e513f2d61a02cb53313749ec6e477
[2025-04-30T08:41:38.147569778Z TRACE quiche::tls] 4484685b5b9e513f2d61a02cb53313749ec6e477 write message lvl=Initial len=266
[2025-04-30T08:41:38.147589645Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx pkt Initial version=1 dcid=2e7adc0aa54c4fcb355d33646fce083d scid=4484685b5b9e513f2d61a02cb53313749ec6e477 len=270 pn=0 
[2025-04-30T08:41:38.147592661Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm CRYPTO off=0 len=266
[2025-04-30T08:41:38.147606537Z TRACE quiche::recovery::congestion::recovery] 4484685b5b9e513f2d61a02cb53313749ec6e477 timer=998.926323ms 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: 229, tv_nsec: 729101818 }, first_sent_time: Instant { tv_sec: 229, tv_nsec: 729101818 }, 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: 229, tv_nsec: 729101818 }, next_time: Instant { tv_sec: 229, tv_nsec: 729101818 }, 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-30T08:41:38.147675766Z TRACE quiche_apps::client] written 1200
[2025-04-30T08:41:38.186579644Z TRACE quiche_apps::client] got 1280 bytes from 193.167.100.100:443 to 0.0.0.0:40853
[2025-04-30T08:41:38.186612757Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx pkt Initial version=1 dcid=4484685b5b9e513f2d61a02cb53313749ec6e477 scid=8d2df001 token= len=320 pn=0 
[2025-04-30T08:41:38.186629307Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T08:41:38.186636811Z TRACE quiche::recovery::congestion::recovery] 4484685b5b9e513f2d61a02cb53313749ec6e477 packet newly acked 0
[2025-04-30T08:41:38.186644195Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm PADDING len=203
[2025-04-30T08:41:38.186648393Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm CRYPTO off=0 len=90
[2025-04-30T08:41:38.186762647Z TRACE quiche::tls] 4484685b5b9e513f2d61a02cb53313749ec6e477 set write secret lvl=Handshake
[2025-04-30T08:41:38.186774659Z TRACE quiche::tls] 4484685b5b9e513f2d61a02cb53313749ec6e477 set read secret lvl=Handshake
[2025-04-30T08:41:38.186810115Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx pkt Handshake version=1 dcid=4484685b5b9e513f2d61a02cb53313749ec6e477 scid=8d2df001 len=734 pn=0 
[2025-04-30T08:41:38.186817108Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm CRYPTO off=0 len=712
[2025-04-30T08:41:38.187066906Z TRACE quiche::tls] 4484685b5b9e513f2d61a02cb53313749ec6e477 write message lvl=Handshake len=36
[2025-04-30T08:41:38.187072145Z TRACE quiche::tls] 4484685b5b9e513f2d61a02cb53313749ec6e477 set write secret lvl=OneRTT
[2025-04-30T08:41:38.187080451Z TRACE quiche::tls] 4484685b5b9e513f2d61a02cb53313749ec6e477 set read secret lvl=OneRTT
[2025-04-30T08:41:38.187099677Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 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(2e7adc0aa54c4fcb355d33646fce083d), max_idle_timeout: 30000, stateless_reset_token: Some(49187493003268445310791375922754009324), max_udp_payload_size: 1452, initial_max_data: 786432, initial_max_stream_data_bidi_local: 524288, initial_max_stream_data_bidi_remote: 524288, initial_max_stream_data_uni: 524288, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 26, disable_active_migration: false, active_conn_id_limit: 4, initial_source_connection_id: Some(8d2df001), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T08:41:38.187136115Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx pkt Short dcid=4484685b5b9e513f2d61a02cb53313749ec6e477 key_phase=false len=138 pn=0 
[2025-04-30T08:41:38.187144010Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[83, 87, db, 94] reset_token=[9c, e3, 3a, b4, 93, 51, 46, 2d, a0, a7, 2f, b9, 31, 77, fc, 95]
[2025-04-30T08:41:38.187152055Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[81, b4, c0, 65] reset_token=[d9, 09, 95, 71, a4, e6, 69, 5e, 62, 11, e1, 07, 0d, 09, cb, 18]
[2025-04-30T08:41:38.187157896Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[27, 39, d9, 7e] reset_token=[0e, 41, f5, 8a, fd, 94, 39, 15, b4, 3a, a1, 9d, d7, f1, f0, a9]
[2025-04-30T08:41:38.187163606Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[1e, 48, 83, 3e] reset_token=[bb, 8a, 53, c1, 20, c3, 3b, 9b, 41, 04, 0e, 22, 47, 41, 0f, bf]
[2025-04-30T08:41:38.187169798Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[be, 98, a8, cb] reset_token=[43, 2c, b8, fd, c8, 77, 8e, d5, 8f, b9, fc, dc, 63, b5, 03, 38]
[2025-04-30T08:41:38.187179015Z TRACE quiche_apps::client] 0.0.0.0:40853: processed 1280 bytes
[2025-04-30T08:41:38.187184635Z TRACE quiche_apps::client] 0.0.0.0:40853: recv() would block
[2025-04-30T08:41:38.187188082Z TRACE quiche_apps::client] done reading
[2025-04-30T08:41:38.187197870Z DEBUG quiche_apps::common] sending HTTP request "GET /jkxmeznduv\r\n"
[2025-04-30T08:41:38.187268232Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx pkt Initial version=1 dcid=8d2df001 scid=4484685b5b9e513f2d61a02cb53313749ec6e477 len=6 pn=1 
[2025-04-30T08:41:38.187273001Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm ACK delay=82 blocks=[0..0] ecn_counts=None
[2025-04-30T08:41:38.187283290Z TRACE quiche::recovery::congestion::recovery] 4484685b5b9e513f2d61a02cb53313749ec6e477 timer=116.540396ms latest_rtt=39.072624ms srtt=39.072624ms min_rtt=39.072624ms rttvar=19.536312ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 768665259 }, first_sent_time: Instant { tv_sec: 229, tv_nsec: 768665259 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8522, is_app_limited: true, interval: 39.072624ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 229, tv_nsec: 729101818 }), send_elapsed: 0ns, ack_elapsed: 39.072624ms, rtt: 39.072624ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 431888, last_update: Instant { tv_sec: 229, tv_nsec: 768665219 }, next_time: Instant { tv_sec: 229, tv_nsec: 768665259 }, 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-30T08:41:38.187307365Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx pkt Handshake version=1 dcid=8d2df001 scid=4484685b5b9e513f2d61a02cb53313749ec6e477 len=45 pn=2 
[2025-04-30T08:41:38.187316161Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm ACK delay=62 blocks=[0..0] ecn_counts=None
[2025-04-30T08:41:38.187319648Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm CRYPTO off=0 len=36
[2025-04-30T08:41:38.187326901Z TRACE quiche::recovery::congestion::recovery] 4484685b5b9e513f2d61a02cb53313749ec6e477 timer=117.149364ms latest_rtt=39.072624ms srtt=39.072624ms min_rtt=39.072624ms rttvar=19.536312ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=95 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 768665259 }, first_sent_time: Instant { tv_sec: 229, tv_nsec: 768665259 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8522, is_app_limited: true, interval: 39.072624ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 229, tv_nsec: 729101818 }), send_elapsed: 0ns, ack_elapsed: 39.072624ms, rtt: 39.072624ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 431888, last_update: Instant { tv_sec: 229, tv_nsec: 768826902 }, next_time: Instant { tv_sec: 229, tv_nsec: 768665259 }, max_datagram_size: 1350, last_packet_size: None, iv: 219.964µ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-30T08:41:38.187340838Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 dropped epoch 0 state
[2025-04-30T08:41:38.187346669Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13405 out_len=1198 left=1171
[2025-04-30T08:41:38.187360244Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx pkt Short dcid=8d2df001 key_phase=false len=1176 pn=3 
[2025-04-30T08:41:38.187363550Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm ACK delay=26 blocks=[0..0] ecn_counts=None
[2025-04-30T08:41:38.187366816Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[1f, 37, 55, 59, da, 0b, e8, d8, d4, 58, 6e, 32, 7d, 13, 59, fc, b9, 4a, 9d, 67] reset_token=[0f, 14, b3, bf, e1, 46, ee, db, 78, a3, ed, 8b, 89, 2a, 2c, a2]
[2025-04-30T08:41:38.187374110Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[59, d5, 67, 55, 2d, 30, c0, 52, d7, 23, 03, 8b, 00, 16, 0a, 8b, 32, f7, 18, 57] reset_token=[fb, 25, 04, e5, cf, 7f, fb, fd, cd, 08, 35, 8a, 02, 2d, 79, ed]
[2025-04-30T08:41:38.187381463Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[50, 3f, c3, ab, c5, 6a, d2, 44, a6, 9c, 75, cd, fc, 72, 81, d7, e5, 5b, 95, 02] reset_token=[cd, 36, 00, f8, c1, 1d, aa, 01, 71, 9a, 36, 9e, 9b, c4, 1a, db]
[2025-04-30T08:41:38.187388266Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T08:41:38.187391512Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm PADDING len=1029
[2025-04-30T08:41:38.187400369Z TRACE quiche::recovery::congestion::recovery] 4484685b5b9e513f2d61a02cb53313749ec6e477 timer=117.075847ms latest_rtt=39.072624ms srtt=39.072624ms min_rtt=39.072624ms rttvar=19.536312ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1293 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 229, tv_nsec: 768665259 }, first_sent_time: Instant { tv_sec: 229, tv_nsec: 768665259 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8522, is_app_limited: true, interval: 39.072624ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 229, tv_nsec: 729101818 }), send_elapsed: 0ns, ack_elapsed: 39.072624ms, rtt: 39.072624ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1198, rate: 431888, last_update: Instant { tv_sec: 229, tv_nsec: 768826902 }, next_time: Instant { tv_sec: 229, tv_nsec: 769046866 }, max_datagram_size: 1350, last_packet_size: Some(1198), 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-30T08:41:38.187490718Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:40853 to 193.167.100.100:443
[2025-04-30T08:41:38.187501788Z TRACE quiche_apps::client] 0.0.0.0:40853 -> 193.167.100.100:443: done writing
[2025-04-30T08:41:38.219825474Z TRACE quiche_apps::client] got 278 bytes from 193.167.100.100:443 to 0.0.0.0:40853
[2025-04-30T08:41:38.219846353Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx pkt Short dcid=1f375559da0be8d8d4586e327d1359fcb94a9d67 key_phase=false len=257 pn=1 
[2025-04-30T08:41:38.219856482Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 path ID 0 now see SCID with seq num 1
[2025-04-30T08:41:38.219862994Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm ACK delay=51 blocks=[3..3] ecn_counts=None
[2025-04-30T08:41:38.219870087Z TRACE quiche::recovery::congestion::recovery] 4484685b5b9e513f2d61a02cb53313749ec6e477 packet newly acked 3
[2025-04-30T08:41:38.219877161Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm HANDSHAKE_DONE
[2025-04-30T08:41:38.219883763Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 dropped epoch 1 state
[2025-04-30T08:41:38.219887380Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-04-30T08:41:38.219891898Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm CRYPTO off=0 len=138
[2025-04-30T08:41:38.219925421Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm NEW_TOKEN len=86
[2025-04-30T08:41:38.219949015Z TRACE quiche_apps::client] 0.0.0.0:40853: processed 278 bytes
[2025-04-30T08:41:38.219954435Z TRACE quiche_apps::client] 0.0.0.0:40853: recv() would block
[2025-04-30T08:41:38.219957932Z TRACE quiche_apps::client] done reading
[2025-04-30T08:41:38.219961628Z INFO  quiche_apps::client] Retiring source CID 4484685b5b9e513f2d61a02cb53313749ec6e477
[2025-04-30T08:41:38.219975294Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1323
[2025-04-30T08:41:38.219982187Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx pkt Short dcid=8d2df001 key_phase=false len=45 pn=4 
[2025-04-30T08:41:38.219985693Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm ACK delay=16 blocks=[1..1] ecn_counts=None
[2025-04-30T08:41:38.219989581Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=1 conn_id=[64, 01, c8, 3d, 03, be, 3c, ac, f7, f8, f2, e8, e7, 78, 49, aa, 4a, 74, ac, 74] reset_token=[e9, 3c, 22, f1, 39, 8c, 04, fe, 0d, 87, 76, 92, 5a, 3b, 58, 50]
[2025-04-30T08:41:38.220004068Z TRACE quiche::recovery::congestion::recovery] 4484685b5b9e513f2d61a02cb53313749ec6e477 timer=129.517923ms latest_rtt=32.363176ms srtt=38.233943ms min_rtt=32.363176ms rttvar=16.329596ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=67 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1531, delivered_time: Instant { tv_sec: 229, tv_nsec: 801538402 }, first_sent_time: Instant { tv_sec: 229, tv_nsec: 801538402 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36585, is_app_limited: true, interval: 32.744783ms, delivered: 1198, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 229, tv_nsec: 768665259 }), send_elapsed: 381.607µs, ack_elapsed: 32.744783ms, rtt: 32.363176ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 67, rate: 441361, last_update: Instant { tv_sec: 229, tv_nsec: 801538402 }, next_time: Instant { tv_sec: 229, tv_nsec: 801538402 }, max_datagram_size: 1350, last_packet_size: Some(67), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T08:41:38.220070832Z TRACE quiche_apps::client] written 67 bytes from 0.0.0.0:40853 to 193.167.100.100:443
[2025-04-30T08:41:38.220077785Z TRACE quiche_apps::client] 0.0.0.0:40853 -> 193.167.100.100:443: done writing
[2025-04-30T08:41:38.220908979Z TRACE quiche_apps::client] got 1065 bytes from 193.167.100.100:443 to 0.0.0.0:40853
[2025-04-30T08:41:38.220915702Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx pkt Short dcid=1f375559da0be8d8d4586e327d1359fcb94a9d67 key_phase=false len=1044 pn=2 
[2025-04-30T08:41:38.220922134Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 rx frm STREAM id=0 off=0 len=1024 fin=true
[2025-04-30T08:41:38.220929909Z TRACE quiche_apps::client] 0.0.0.0:40853: processed 1065 bytes
[2025-04-30T08:41:38.220933505Z TRACE quiche_apps::client] 0.0.0.0:40853: recv() would block
[2025-04-30T08:41:38.220935840Z TRACE quiche_apps::client] done reading
[2025-04-30T08:41:38.220942121Z TRACE quiche_apps::common] received 1024 bytes
[2025-04-30T08:41:38.220944866Z TRACE quiche_apps::common] stream 0 has 1024 bytes (fin? true)
[2025-04-30T08:41:38.220947611Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T08:41:38.220950046Z INFO  quiche_apps::common] 1/1 response(s) received in 73.270673ms, closing...
[2025-04-30T08:41:38.220956318Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13433 out_len=1350 left=1328
[2025-04-30T08:41:38.220959834Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx pkt Short dcid=8d2df001 key_phase=false len=10 pn=5 
[2025-04-30T08:41:38.220962349Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T08:41:38.220991363Z TRACE quiche::recovery::congestion::recovery] 4484685b5b9e513f2d61a02cb53313749ec6e477 timer=129.514607ms latest_rtt=32.363176ms srtt=38.233943ms min_rtt=32.363176ms rttvar=16.329596ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1531, delivered_time: Instant { tv_sec: 229, tv_nsec: 801538402 }, first_sent_time: Instant { tv_sec: 229, tv_nsec: 801538402 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { delivery_rate: 36585, is_app_limited: true, interval: 32.744783ms, delivered: 1198, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 229, tv_nsec: 768665259 }), send_elapsed: 381.607µs, ack_elapsed: 32.744783ms, rtt: 32.363176ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 441361, last_update: Instant { tv_sec: 229, tv_nsec: 802522251 }, next_time: Instant { tv_sec: 229, tv_nsec: 801538402 }, max_datagram_size: 1350, last_packet_size: None, iv: 224.306µ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-30T08:41:38.221027942Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:40853 to 193.167.100.100:443
[2025-04-30T08:41:38.221031639Z TRACE quiche_apps::client] 0.0.0.0:40853 -> 193.167.100.100:443: done writing
[2025-04-30T08:41:38.532389153Z TRACE quiche_apps::client] timed out
[2025-04-30T08:41:38.532411124Z TRACE quiche] 4484685b5b9e513f2d61a02cb53313749ec6e477 draining timeout expired
[2025-04-30T08:41:38.532489881Z TRACE quiche_apps::client] done reading
[2025-04-30T08:41:38.532495772Z INFO  quiche_apps::client] connection closed, recv=5 sent=6 lost=0 retrans=0 sent_bytes=1782 recv_bytes=2623 lost_bytes=0 [local_addr=0.0.0.0:40853 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=5 sent=6 lost=0 retrans=0 rtt=38.233943ms min_rtt=Some(32.363176ms) rttvar=16.329596ms cwnd=13500 sent_bytes=1782 recv_bytes=2623 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=36585]