[2025-04-30T08:43:29.077026502Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T08:43:29.077325129Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:50502 with scid 8c5c29f65b4eee0329b8c7033e62e1f3e4617553
[2025-04-30T08:43:29.077376705Z TRACE quiche::tls] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 write message lvl=Initial len=524
[2025-04-30T08:43:29.077392936Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Initial version=1 dcid=d64408873d4b82bdc8655be8ee350599 scid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 len=528 pn=0 
[2025-04-30T08:43:29.077396542Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm CRYPTO off=0 len=524
[2025-04-30T08:43:29.077410348Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=998.922936ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=591 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 343, tv_nsec: 445459594 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 445459594 }, 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: 343, tv_nsec: 445459594 }, next_time: Instant { tv_sec: 343, tv_nsec: 445459594 }, 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:43:29.077513280Z TRACE quiche_apps::client] written 1200
[2025-04-30T08:43:29.111249215Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.111275344Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Initial version=1 dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 scid=3fabfda6b530a84eb1e95f69446a6a6f416d token= len=125 pn=1854644546 
[2025-04-30T08:43:29.111290803Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T08:43:29.111297646Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 packet newly acked 0
[2025-04-30T08:43:29.111304438Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm CRYPTO off=0 len=96
[2025-04-30T08:43:29.111404896Z TRACE quiche::tls] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 set write secret lvl=Handshake
[2025-04-30T08:43:29.111416157Z TRACE quiche::tls] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 set read secret lvl=Handshake
[2025-04-30T08:43:29.111444400Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Handshake version=1 dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 scid=3fabfda6b530a84eb1e95f69446a6a6f416d len=187 pn=1854644546 
[2025-04-30T08:43:29.111450892Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm CRYPTO off=0 len=163
[2025-04-30T08:43:29.111482601Z TRACE quiche::tls] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 write message lvl=Handshake len=36
[2025-04-30T08:43:29.111486388Z TRACE quiche::tls] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 set write secret lvl=OneRTT
[2025-04-30T08:43:29.111493591Z TRACE quiche::tls] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 set read secret lvl=OneRTT
[2025-04-30T08:43:29.111510693Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=true TransportParams { original_destination_connection_id: Some(d64408873d4b82bdc8655be8ee350599), max_idle_timeout: 30000, stateless_reset_token: Some(177525496007734059276226347222291467409), max_udp_payload_size: 65527, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 0, initial_max_stream_data_bidi_remote: 262144, initial_max_stream_data_uni: 262144, initial_max_streams_bidi: 100, initial_max_streams_uni: 0, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 7, initial_source_connection_id: Some(3fabfda6b530a84eb1e95f69446a6a6f416d), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T08:43:29.111544336Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=768 pn=1854644546 
[2025-04-30T08:43:29.111552311Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[4b, a4, f8, 64, 07, 62, c3, 19, 41, 13, 76, 71, 78, af, b4, ac, 65, ff] reset_token=[c2, cc, 31, f1, 28, 58, 2f, a0, 79, a5, c2, 28, c4, b1, e2, 2d]
[2025-04-30T08:43:29.111561107Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[fa, 29, 27, aa, c1, ca, 16, 8f, bf, 13, de, 9f, a8, 08, e2, 44, 8d, 53] reset_token=[ad, e6, 4a, c7, 49, 3e, 64, 89, 8f, aa, 7e, 1b, 57, c0, 0f, 04]
[2025-04-30T08:43:29.111567940Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[a0, 63, aa, ce, c0, af, 75, 3c, ca, 11, ca, fe, 97, d1, d8, 1d, b5, a7] reset_token=[7a, 0e, a2, 9b, cb, a0, 7e, bf, 42, 47, 9a, f8, c0, 43, 71, 41]
[2025-04-30T08:43:29.111574061Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[fb, 76, de, 97, e7, 22, 6a, 70, 16, b2, 20, 09, 9f, 1a, 16, 77, c5, 74] reset_token=[a8, c7, d0, 84, 65, 5c, b0, be, 52, 21, 39, ac, 95, 16, 82, f4]
[2025-04-30T08:43:29.111580744Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[87, 1f, a2, 1c, 02, 8e, 53, 15, 7a, 5e, 99, 25, 2f, 23, 7f, b1, f6, db] reset_token=[c6, c9, 31, 89, 3f, 66, bd, 4a, 2e, 5b, 68, a0, fc, 22, f5, 3a]
[2025-04-30T08:43:29.111587046Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[72, 74, 6f, a5, ab, 7c, f2, 5b, cb, e9, 54, bf, 93, 8a, c2, 6e, 2d, 7c] reset_token=[87, f4, 4d, 8f, cd, d2, 4f, a7, bf, 9e, 13, 76, d3, 0a, 3a, fc]
[2025-04-30T08:43:29.111593257Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[64, e6, 81, 3d, 00, eb, 8e, f1, f8, 42, 32, cf, 78, c3, 83, 17, f0, 71] reset_token=[8f, fe, f3, 3d, f5, 4b, 62, 02, 15, 5c, 6f, 9c, ed, 66, 75, 25]
[2025-04-30T08:43:29.111600340Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm PADDING len=482
[2025-04-30T08:43:29.111609788Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.111615068Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.111618364Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.111627892Z DEBUG quiche_apps::common] sending HTTP request "GET /gjfcbscpng\r\n"
[2025-04-30T08:43:29.111707150Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Initial version=1 dcid=3fabfda6b530a84eb1e95f69446a6a6f416d scid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 len=12 pn=1 
[2025-04-30T08:43:29.111711778Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=54 blocks=[1854644546..1854644546] ecn_counts=None
[2025-04-30T08:43:29.111722127Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=101.351593ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 479633717 }, next_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, 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:43:29.111751172Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Handshake version=1 dcid=3fabfda6b530a84eb1e95f69446a6a6f416d scid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 len=52 pn=2 
[2025-04-30T08:43:29.111754808Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=38 blocks=[1854644546..1854644546] ecn_counts=None
[2025-04-30T08:43:29.111758105Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm CRYPTO off=0 len=36
[2025-04-30T08:43:29.111765479Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=101.736311ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=116 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 479822499 }, next_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, max_datagram_size: 1350, last_packet_size: None, iv: 233.272µ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:43:29.111778743Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 dropped epoch 0 state
[2025-04-30T08:43:29.111784294Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13384 out_len=1157 left=1109
[2025-04-30T08:43:29.111797999Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=1121 pn=3 
[2025-04-30T08:43:29.111801205Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=29 blocks=[1854644546..1854644546] ecn_counts=None
[2025-04-30T08:43:29.111804501Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[1e, 00, f1, 33, 1b, 56, 54, f4, e9, 0c, bb, 8d, 86, 85, 6e, 11, 51, 50, 31, d5] reset_token=[8c, 4d, 23, d4, 57, 34, 14, ec, 68, c0, 2c, 82, f2, 08, 79, 9f]
[2025-04-30T08:43:29.111811585Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[52, 6d, 8d, f8, 09, fd, d4, 55, 35, 37, 65, cc, 55, ba, af, ab, 1c, 13, 4c, 38] reset_token=[d1, eb, 17, 89, 2d, 69, 38, 20, 34, 5a, f6, b6, 64, 97, e3, 62]
[2025-04-30T08:43:29.111817826Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[54, 1e, a4, 69, ab, af, df, 5f, f8, 8c, 51, 7d, 09, c9, 6b, ed, 0e, 71, 8a, 68] reset_token=[14, e0, 9b, 4a, 80, b4, e9, ef, 7c, 35, 46, 81, 17, ae, d5, 9a]
[2025-04-30T08:43:29.111827925Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[e7, 8f, af, db, 88, 58, e4, 8a, f6, ca, 92, db, ae, 00, b0, ff, a6, 9b, 5c, b9] reset_token=[fe, 1b, ec, 84, 09, 5f, bf, 78, 78, e1, 08, 7f, b4, a0, ad, 83]
[2025-04-30T08:43:29.111834217Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[5a, a9, 04, ba, 89, 52, 39, f5, 03, a5, 66, 4b, 06, f9, 41, 2f, f5, f2, ef, e9] reset_token=[16, b2, e3, 1c, 86, 9d, dc, 13, 17, f1, 6b, 58, 37, d9, d3, 3d]
[2025-04-30T08:43:29.111840378Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[41, 26, 11, aa, 59, 9a, 3b, 45, 6a, 19, a6, bc, 16, f7, 08, 3c, d9, d5, 1c, 78] reset_token=[c3, aa, 49, 95, d4, f3, 11, f6, be, 26, 6e, 0f, 72, bc, d6, fb]
[2025-04-30T08:43:29.111846299Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T08:43:29.111849435Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm PADDING len=847
[2025-04-30T08:43:29.111860255Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=101.641524ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1273 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1157, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 479822499 }, next_time: Instant { tv_sec: 343, tv_nsec: 480055771 }, max_datagram_size: 1350, last_packet_size: Some(1157), 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:43:29.111941327Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.111952207Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.145484713Z TRACE quiche_apps::client] got 1342 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.145511793Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1321 pn=1854644547 
[2025-04-30T08:43:29.145526320Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm PING mtu_probe=None
[2025-04-30T08:43:29.145536299Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm PADDING len=1303
[2025-04-30T08:43:29.145551537Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1342 bytes
[2025-04-30T08:43:29.145556817Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.145560354Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.145573609Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12227 out_len=1350 left=1302
[2025-04-30T08:43:29.145579690Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=4 
[2025-04-30T08:43:29.145582806Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=8 blocks=[1854644546..1854644547] ecn_counts=None
[2025-04-30T08:43:29.145603965Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=67.897403ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1273 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.145662795Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.145668556Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.146403076Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.146413345Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644548 
[2025-04-30T08:43:29.146419837Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm HANDSHAKE_DONE
[2025-04-30T08:43:29.146426400Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 dropped epoch 1 state
[2025-04-30T08:43:29.146431629Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm NEW_TOKEN len=41
[2025-04-30T08:43:29.146434955Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm CRYPTO off=0 len=237
[2025-04-30T08:43:29.146457628Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=0 len=873 fin=false
[2025-04-30T08:43:29.146491411Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.146495759Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.146498183Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.146503403Z TRACE quiche_apps::common] received 873 bytes
[2025-04-30T08:43:29.146506318Z TRACE quiche_apps::common] stream 0 has 873 bytes (fin? false)
[2025-04-30T08:43:29.146514023Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.146517339Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=5 
[2025-04-30T08:43:29.146519824Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=12 blocks=[1854644546..1854644548] ecn_counts=None
[2025-04-30T08:43:29.146526687Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=91.975122ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.146566341Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.146570859Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.147383906Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.147393954Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644549 
[2025-04-30T08:43:29.147400797Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=873 len=1156 fin=false
[2025-04-30T08:43:29.147408432Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.147411958Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.147414352Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.147418520Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:43:29.147420844Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:43:29.147427407Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.147430593Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=6 
[2025-04-30T08:43:29.147433017Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=4 blocks=[1854644546..1854644549] ecn_counts=None
[2025-04-30T08:43:29.147439339Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=91.06245ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.147472841Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.147476859Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.148366399Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.148376367Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644550 
[2025-04-30T08:43:29.148388179Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=2029 len=1156 fin=false
[2025-04-30T08:43:29.148395633Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.148399230Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.148401594Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.148405622Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:43:29.148407836Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:43:29.148414298Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.148417434Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=7 
[2025-04-30T08:43:29.148419838Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=4 blocks=[1854644546..1854644550] ecn_counts=None
[2025-04-30T08:43:29.148426561Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=90.075278ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.148461366Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.148465433Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.149351887Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.149361715Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644551 
[2025-04-30T08:43:29.149368408Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=3185 len=1156 fin=false
[2025-04-30T08:43:29.149375571Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.149379088Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.149381462Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.149385490Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:43:29.149387734Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:43:29.149394376Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.149397512Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=8 
[2025-04-30T08:43:29.149399887Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=4 blocks=[1854644546..1854644551] ecn_counts=None
[2025-04-30T08:43:29.149406068Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=89.095711ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.149445251Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.149449459Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.150333088Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.150342986Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644552 
[2025-04-30T08:43:29.150349708Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=4341 len=1156 fin=false
[2025-04-30T08:43:29.150356762Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.150360388Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.150362763Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.150366770Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:43:29.150368964Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:43:29.150375697Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.150378833Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=9 
[2025-04-30T08:43:29.150381217Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=4 blocks=[1854644546..1854644552] ecn_counts=None
[2025-04-30T08:43:29.150387249Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=88.114591ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.150426231Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.150430419Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.151318005Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.151328024Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644553 
[2025-04-30T08:43:29.151334556Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=5497 len=1156 fin=false
[2025-04-30T08:43:29.151341739Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.151345246Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.151347580Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.151351738Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:43:29.151353912Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:43:29.151360454Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.151363620Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=10 
[2025-04-30T08:43:29.151366055Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=4 blocks=[1854644546..1854644553] ecn_counts=None
[2025-04-30T08:43:29.151372286Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=87.129563ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 10, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.151406119Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.151410026Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.152300167Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.152307571Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644554 
[2025-04-30T08:43:29.152313963Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=6653 len=1156 fin=false
[2025-04-30T08:43:29.152320916Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.152324302Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.152326647Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.152330604Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:43:29.152332798Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:43:29.152345261Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.152348437Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=11 
[2025-04-30T08:43:29.152350822Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=4 blocks=[1854644546..1854644554] ecn_counts=None
[2025-04-30T08:43:29.152357394Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=86.144445ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 11, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.152390245Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.152394112Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.153286978Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.153296987Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644555 
[2025-04-30T08:43:29.153303669Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=7809 len=1156 fin=false
[2025-04-30T08:43:29.153310933Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.153314559Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.153316944Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.153321002Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:43:29.153323256Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:43:29.153345087Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.153348523Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=12 
[2025-04-30T08:43:29.153350897Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=6 blocks=[1854644546..1854644555] ecn_counts=None
[2025-04-30T08:43:29.153357249Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=85.14458ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 12, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.153393927Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.153398015Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.154271585Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.154282185Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644556 
[2025-04-30T08:43:29.154289208Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=8965 len=1156 fin=false
[2025-04-30T08:43:29.154296491Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.154300028Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.154302372Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.154306540Z TRACE quiche_apps::common] received 1156 bytes
[2025-04-30T08:43:29.154308694Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false)
[2025-04-30T08:43:29.154315386Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12343 out_len=1350 left=1302
[2025-04-30T08:43:29.154318523Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=12 pn=13 
[2025-04-30T08:43:29.154320967Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm ACK delay=4 blocks=[1854644546..1854644556] ecn_counts=None
[2025-04-30T08:43:29.154327199Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=84.174641ms latest_rtt=33.935067ms srtt=33.935067ms min_rtt=33.935067ms rttvar=16.967533ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 591, delivered_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 479633757 }, end_of_app_limited: 2, last_sent_packet: 13, largest_acked: 0, rate_sample: RateSample { delivery_rate: 17415, is_app_limited: true, interval: 33.935067ms, delivered: 591, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 445459594 }), send_elapsed: 0ns, ack_elapsed: 33.935067ms, rtt: 33.935067ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 497273, last_update: Instant { tv_sec: 343, tv_nsec: 513691673 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, 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:43:29.154362124Z TRACE quiche_apps::client] written 48 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.154366051Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.155253066Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.155266100Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=1179 pn=1854644557 
[2025-04-30T08:43:29.155283242Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm STREAM id=0 off=10121 len=119 fin=true
[2025-04-30T08:43:29.155290977Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm PADDING len=1037
[2025-04-30T08:43:29.155298350Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 1200 bytes
[2025-04-30T08:43:29.155303450Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:50502
[2025-04-30T08:43:29.155306566Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx pkt Short dcid=8c5c29f65b4eee0329b8c7033e62e1f3e4617553 key_phase=false len=23 pn=1854644558 
[2025-04-30T08:43:29.155310683Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 rx frm ACK delay=552 blocks=[3..3] ecn_counts=None
[2025-04-30T08:43:29.155314240Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 packet newly acked 3
[2025-04-30T08:43:29.155324780Z TRACE quiche_apps::client] 0.0.0.0:50502: processed 44 bytes
[2025-04-30T08:43:29.155328256Z TRACE quiche_apps::client] 0.0.0.0:50502: recv() would block
[2025-04-30T08:43:29.155330620Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.155336051Z TRACE quiche_apps::common] received 119 bytes
[2025-04-30T08:43:29.155338295Z TRACE quiche_apps::common] stream 0 has 119 bytes (fin? true)
[2025-04-30T08:43:29.155340569Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T08:43:29.155342974Z INFO  quiche_apps::common] 1/1 response(s) received in 77.825526ms, closing...
[2025-04-30T08:43:29.155349786Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1314
[2025-04-30T08:43:29.155353133Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx pkt Short dcid=3fabfda6b530a84eb1e95f69446a6a6f416d key_phase=false len=10 pn=14 
[2025-04-30T08:43:29.155355507Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T08:43:29.155362620Z TRACE quiche::recovery::congestion::recovery] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 timer=115.474923ms latest_rtt=43.375912ms srtt=34.563172ms min_rtt=33.935067ms rttvar=13.98186ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=46 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1748, delivered_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, first_sent_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 3, rate_sample: RateSample { delivery_rate: 26416, is_app_limited: true, interval: 43.797926ms, delivered: 1157, prior_delivered: 591, prior_time: Some(Instant { tv_sec: 343, tv_nsec: 479633757 }), send_elapsed: 422.014µs, ack_elapsed: 43.797926ms, rtt: 43.375912ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 488236, last_update: Instant { tv_sec: 343, tv_nsec: 523472770 }, next_time: Instant { tv_sec: 343, tv_nsec: 513691673 }, max_datagram_size: 1350, last_packet_size: None, iv: 94.217µ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:43:29.155398507Z TRACE quiche_apps::client] written 46 bytes from 0.0.0.0:50502 to 193.167.100.100:443
[2025-04-30T08:43:29.155402214Z TRACE quiche_apps::client] 0.0.0.0:50502 -> 193.167.100.100:443: done writing
[2025-04-30T08:43:29.427706394Z TRACE quiche_apps::client] timed out
[2025-04-30T08:43:29.427728585Z TRACE quiche] 8c5c29f65b4eee0329b8c7033e62e1f3e4617553 draining timeout expired
[2025-04-30T08:43:29.427766716Z TRACE quiche_apps::client] done reading
[2025-04-30T08:43:29.427771004Z INFO  quiche_apps::client] connection closed, recv=15 sent=15 lost=0 retrans=0 sent_bytes=2467 recv_bytes=14586 lost_bytes=0 [local_addr=0.0.0.0:50502 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=15 sent=15 lost=0 retrans=0 rtt=34.563172ms min_rtt=Some(33.935067ms) rttvar=13.98186ms cwnd=13500 sent_bytes=2467 recv_bytes=14586 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=26416]