[2025-04-30T19:44:32.853512546Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T19:44:32.853835145Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:52795 with scid 6dcb970a5a18153c0082f11176af1e9c98b645bf
[2025-04-30T19:44:32.853889557Z TRACE quiche::tls] 6dcb970a5a18153c0082f11176af1e9c98b645bf write message lvl=Initial len=266
[2025-04-30T19:44:32.853907070Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx pkt Initial version=1 dcid=5f0c606e66771539a18cad698126dd88 scid=6dcb970a5a18153c0082f11176af1e9c98b645bf len=270 pn=0 
[2025-04-30T19:44:32.853910456Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm CRYPTO off=0 len=266
[2025-04-30T19:44:32.853924693Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf timer=998.917314ms 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: 128, tv_nsec: 486630755 }, first_sent_time: Instant { tv_sec: 128, tv_nsec: 486630755 }, 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: 128, tv_nsec: 486630755 }, next_time: Instant { tv_sec: 128, tv_nsec: 486630755 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T19:44:32.854014082Z TRACE quiche_apps::client] written 1200
[2025-04-30T19:44:32.894835919Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:52795
[2025-04-30T19:44:32.894871476Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx pkt Initial version=1 dcid=6dcb970a5a18153c0082f11176af1e9c98b645bf scid=5504ff8a2353d0546f50 token= len=116 pn=0 
[2025-04-30T19:44:32.894888929Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T19:44:32.894896544Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf packet newly acked 0
[2025-04-30T19:44:32.894903777Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm CRYPTO off=0 len=90
[2025-04-30T19:44:32.895023513Z TRACE quiche::tls] 6dcb970a5a18153c0082f11176af1e9c98b645bf set write secret lvl=Handshake
[2025-04-30T19:44:32.895035766Z TRACE quiche::tls] 6dcb970a5a18153c0082f11176af1e9c98b645bf set read secret lvl=Handshake
[2025-04-30T19:44:32.895072956Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx pkt Handshake version=1 dcid=6dcb970a5a18153c0082f11176af1e9c98b645bf scid=5504ff8a2353d0546f50 len=751 pn=0 
[2025-04-30T19:44:32.895080751Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm CRYPTO off=0 len=730
[2025-04-30T19:44:32.895387539Z TRACE quiche::tls] 6dcb970a5a18153c0082f11176af1e9c98b645bf write message lvl=Handshake len=36
[2025-04-30T19:44:32.895394883Z TRACE quiche::tls] 6dcb970a5a18153c0082f11176af1e9c98b645bf set write secret lvl=OneRTT
[2025-04-30T19:44:32.895403619Z TRACE quiche::tls] 6dcb970a5a18153c0082f11176af1e9c98b645bf set read secret lvl=OneRTT
[2025-04-30T19:44:32.895423317Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf 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(5f0c606e66771539a18cad698126dd88), max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 4611686018427387903, initial_max_stream_data_bidi_local: 1048576, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 1048576, initial_max_streams_bidi: 16, initial_max_streams_uni: 16, ack_delay_exponent: 3, max_ack_delay: 20, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(5504ff8a2353d0546f50), retry_source_connection_id: None, max_datagram_frame_size: Some(0), unknown_params: None }
[2025-04-30T19:44:32.895457791Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx pkt Short dcid=0000000000000000000000000000000000000000 key_phase=false len=265 pn=5775386 
[2025-04-30T19:44:32.895463332Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf dropped invalid packet
[2025-04-30T19:44:32.895465987Z TRACE quiche_apps::client] 0.0.0.0:52795: processed 1232 bytes
[2025-04-30T19:44:32.895470676Z TRACE quiche_apps::client] 0.0.0.0:52795: recv() would block
[2025-04-30T19:44:32.895473351Z TRACE quiche_apps::client] done reading
[2025-04-30T19:44:32.895482288Z DEBUG quiche_apps::common] sending HTTP request "GET /hgiedpfuov\r\n"
[2025-04-30T19:44:32.895557049Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx pkt Initial version=1 dcid=5504ff8a2353d0546f50 scid=6dcb970a5a18153c0082f11176af1e9c98b645bf len=6 pn=1 
[2025-04-30T19:44:32.895561056Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm ACK delay=86 blocks=[0..0] ecn_counts=None
[2025-04-30T19:44:32.895570314Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf timer=122.362668ms latest_rtt=41.022696ms srtt=41.022696ms min_rtt=41.022696ms rttvar=20.511348ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 128, tv_nsec: 528209359 }, first_sent_time: Instant { tv_sec: 128, tv_nsec: 528209359 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8117, is_app_limited: true, interval: 41.022696ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 128, tv_nsec: 486630755 }), send_elapsed: 0ns, ack_elapsed: 41.022696ms, rtt: 41.022696ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 411357, last_update: Instant { tv_sec: 128, tv_nsec: 528209319 }, next_time: Instant { tv_sec: 128, tv_nsec: 528209359 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T19:44:32.895591644Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx pkt Handshake version=1 dcid=5504ff8a2353d0546f50 scid=6dcb970a5a18153c0082f11176af1e9c98b645bf len=46 pn=2 
[2025-04-30T19:44:32.895594379Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm ACK delay=64 blocks=[0..0] ecn_counts=None
[2025-04-30T19:44:32.895596924Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm CRYPTO off=0 len=36
[2025-04-30T19:44:32.895602755Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf timer=123.013324ms latest_rtt=41.022696ms srtt=41.022696ms min_rtt=41.022696ms rttvar=20.511348ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=102 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 128, tv_nsec: 528209359 }, first_sent_time: Instant { tv_sec: 128, tv_nsec: 528209359 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8117, is_app_limited: true, interval: 41.022696ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 128, tv_nsec: 486630755 }), send_elapsed: 0ns, ack_elapsed: 41.022696ms, rtt: 41.022696ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 411357, last_update: Instant { tv_sec: 128, tv_nsec: 528336288 }, next_time: Instant { tv_sec: 128, tv_nsec: 528209359 }, max_datagram_size: 1350, last_packet_size: None, iv: 247.96µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T19:44:32.895618965Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf dropped epoch 0 state
[2025-04-30T19:44:32.895623574Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13398 out_len=1185 left=1157
[2025-04-30T19:44:32.895635286Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx pkt Short dcid=5504ff8a2353d0546f50 key_phase=false len=1157 pn=3 
[2025-04-30T19:44:32.895637871Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e9, 6c, 47, 55, 5a, 78, 96, 1e, 91, 3f, fc, 11, b1, dd, 76, bf, 32, fe, 67, 2f] reset_token=[69, e8, 68, c0, 71, 08, 8d, 67, fd, 17, a1, 94, 40, 9a, df, d4]
[2025-04-30T19:44:32.895644714Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[54, 9c, 03, c4, 05, 72, 7b, a1, c2, 7a, 5a, 42, e0, bc, 08, 80, bb, 54, 25, 7c] reset_token=[4b, a9, 06, bb, c9, 16, 3b, 2c, fb, 1d, 4f, 97, db, 50, d4, df]
[2025-04-30T19:44:32.895650354Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[69, d0, 38, 81, 94, 09, 98, 91, 0e, aa, 38, 21, a6, 7d, fb, 50, 6c, e8, 34, a6] reset_token=[97, ed, 58, eb, 37, 4d, f9, d7, 07, a8, ef, 90, 2e, 33, e1, 13]
[2025-04-30T19:44:32.895655624Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[f3, 31, d8, 88, 63, 01, 5b, a3, 4a, 40, e6, 24, 1e, 5b, 9e, d6, b6, 1f, c2, 05] reset_token=[c9, 0e, e0, 72, 79, 98, 70, cb, f6, 33, c8, 8b, 4c, e4, 3e, fd]
[2025-04-30T19:44:32.895660814Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[72, e9, dd, 42, 98, 5b, 20, 23, 6d, fa, 93, e2, e0, a7, 51, b0, ec, 4c, 07, a5] reset_token=[b8, cb, f4, 65, 8f, b0, b5, c6, 9f, aa, 81, bf, 87, 1c, 1f, 2f]
[2025-04-30T19:44:32.895665804Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[c8, 67, 41, 32, 0c, b3, e7, 8b, ad, be, a1, 17, 2a, 59, a2, 9e, c4, 5f, f2, fa] reset_token=[f3, 19, 3e, 14, c4, 74, 40, ee, 1c, e0, ae, 52, ad, ac, c8, a9]
[2025-04-30T19:44:32.895670983Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[73, 71, b2, 9d, 88, 64, fa, 12, ef, a2, e9, 4e, 45, c2, 06, 22, 85, 49, 36, c4] reset_token=[e9, 36, 61, 68, eb, d8, 80, df, 6d, df, 3b, 56, 8a, 91, db, e3]
[2025-04-30T19:44:32.895675913Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T19:44:32.895678438Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm PADDING len=855
[2025-04-30T19:44:32.895687114Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf timer=122.928956ms latest_rtt=41.022696ms srtt=41.022696ms min_rtt=41.022696ms rttvar=20.511348ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1287 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 128, tv_nsec: 528209359 }, first_sent_time: Instant { tv_sec: 128, tv_nsec: 528209359 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8117, is_app_limited: true, interval: 41.022696ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 128, tv_nsec: 486630755 }), send_elapsed: 0ns, ack_elapsed: 41.022696ms, rtt: 41.022696ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1185, rate: 411357, last_update: Instant { tv_sec: 128, tv_nsec: 528336288 }, next_time: Instant { tv_sec: 128, tv_nsec: 528584248 }, max_datagram_size: 1350, last_packet_size: Some(1185), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T19:44:32.895748399Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:52795 to 193.167.100.100:443
[2025-04-30T19:44:32.895756264Z TRACE quiche_apps::client] 0.0.0.0:52795 -> 193.167.100.100:443: done writing
[2025-04-30T19:44:32.929257789Z TRACE quiche_apps::client] got 1332 bytes from 193.167.100.100:443 to 0.0.0.0:52795
[2025-04-30T19:44:32.929290822Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx pkt Handshake version=1 dcid=6dcb970a5a18153c0082f11176af1e9c98b645bf scid=5504ff8a2353d0546f50 len=22 pn=1 
[2025-04-30T19:44:32.929305750Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm ACK delay=0 blocks=[2..2] ecn_counts=None
[2025-04-30T19:44:32.929313193Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf packet newly acked 2
[2025-04-30T19:44:32.929340725Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx pkt Short dcid=6dcb970a5a18153c0082f11176af1e9c98b645bf key_phase=false len=1250 pn=0 
[2025-04-30T19:44:32.929348610Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm ACK delay=0 blocks=[3..3] ecn_counts=None
[2025-04-30T19:44:32.929352297Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf packet newly acked 3
[2025-04-30T19:44:32.929356816Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm HANDSHAKE_DONE
[2025-04-30T19:44:32.929362216Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf dropped epoch 1 state
[2025-04-30T19:44:32.929367115Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[4b, 53, 59, 25, 6c, 1b, 31, 91, 58, 5e] reset_token=[77, 84, 33, 94, 02, 98, 37, 84, 25, 26, 65, 05, eb, fe, 48, 5c]
[2025-04-30T19:44:32.929375912Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[bd, 30, 39, 33, 6f, df, 70, c5, 02, 97] reset_token=[1a, 31, f3, cf, c9, 04, ca, 5b, 3e, 9b, 40, e7, 18, b1, a9, 7b]
[2025-04-30T19:44:32.929382364Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[6c, 27, 22, 3f, 44, f4, 8b, dc, 6d, 3c] reset_token=[60, c1, d8, 4e, 9b, 6a, 71, 54, 90, 0b, ef, 96, d8, a9, 33, 2e]
[2025-04-30T19:44:32.929388235Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[95, c2, 3d, 4e, 53, 2e, 3a, fd, c4, 60] reset_token=[70, e1, ef, 81, 6c, 6c, 7b, 96, 2b, 25, 2e, 52, 4b, 9d, 89, 29]
[2025-04-30T19:44:32.929394467Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[06, c5, bf, 03, 99, 22, ea, 80, 76, ac] reset_token=[3a, a9, af, c9, 75, a8, 97, e2, ef, 6f, 9e, 34, 29, 78, 1e, 5e]
[2025-04-30T19:44:32.929400729Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[fc, 14, b2, 10, 60, 99, 6f, 48, 19, 6a] reset_token=[0f, 13, 33, c4, 4e, 89, 7a, 1f, cd, ed, f4, 40, 2a, fe, 8f, 9b]
[2025-04-30T19:44:32.929406740Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[d2, ed, cb, 68, b6, c5, 75, 7a, 01, 92] reset_token=[c3, 85, ce, dd, 34, a9, e8, 4e, 88, a2, cd, 9d, 2d, de, 51, e4]
[2025-04-30T19:44:32.929415967Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm PADDING len=1017
[2025-04-30T19:44:32.929463557Z TRACE quiche_apps::client] 0.0.0.0:52795: processed 1332 bytes
[2025-04-30T19:44:32.929470300Z TRACE quiche_apps::client] 0.0.0.0:52795: recv() would block
[2025-04-30T19:44:32.929477393Z TRACE quiche_apps::client] done reading
[2025-04-30T19:44:32.929489737Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:44:32.929496479Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx pkt Short dcid=5504ff8a2353d0546f50 key_phase=false len=5 pn=4 
[2025-04-30T19:44:32.929499625Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm ACK delay=18 blocks=[0..0] ecn_counts=None
[2025-04-30T19:44:32.929509163Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf timer=none latest_rtt=33.543602ms srtt=39.304752ms min_rtt=33.543602ms rttvar=14.526057ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1620, delivered_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, first_sent_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 3, rate_sample: RateSample { delivery_rate: 38005, is_app_limited: true, interval: 33.863327ms, delivered: 1287, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 128, tv_nsec: 528209359 }), send_elapsed: 0ns, ack_elapsed: 33.863327ms, rtt: 33.863327ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 429337, last_update: Instant { tv_sec: 128, tv_nsec: 562271671 }, next_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T19:44:32.929579666Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:52795 to 193.167.100.100:443
[2025-04-30T19:44:32.929588984Z TRACE quiche_apps::client] 0.0.0.0:52795 -> 193.167.100.100:443: done writing
[2025-04-30T19:44:32.930233278Z TRACE quiche_apps::client] got 1332 bytes from 193.167.100.100:443 to 0.0.0.0:52795
[2025-04-30T19:44:32.930243547Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx pkt Short dcid=6dcb970a5a18153c0082f11176af1e9c98b645bf key_phase=false len=1311 pn=1 
[2025-04-30T19:44:32.930250881Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm PING mtu_probe=None
[2025-04-30T19:44:32.930259738Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm PADDING len=1293
[2025-04-30T19:44:32.930267152Z TRACE quiche_apps::client] 0.0.0.0:52795: processed 1332 bytes
[2025-04-30T19:44:32.930271660Z TRACE quiche_apps::client] 0.0.0.0:52795: recv() would block
[2025-04-30T19:44:32.930274576Z TRACE quiche_apps::client] done reading
[2025-04-30T19:44:32.930282240Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:44:32.930286148Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx pkt Short dcid=5504ff8a2353d0546f50 key_phase=false len=5 pn=5 
[2025-04-30T19:44:32.930289144Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm ACK delay=4 blocks=[0..1] ecn_counts=None
[2025-04-30T19:44:32.930296708Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf timer=none latest_rtt=33.543602ms srtt=39.304752ms min_rtt=33.543602ms rttvar=14.526057ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1620, delivered_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, first_sent_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { delivery_rate: 38005, is_app_limited: true, interval: 33.863327ms, delivered: 1287, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 128, tv_nsec: 528209359 }), send_elapsed: 0ns, ack_elapsed: 33.863327ms, rtt: 33.863327ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 429337, last_update: Instant { tv_sec: 128, tv_nsec: 562271671 }, next_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T19:44:32.930348545Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:52795 to 193.167.100.100:443
[2025-04-30T19:44:32.930357492Z TRACE quiche_apps::client] 0.0.0.0:52795 -> 193.167.100.100:443: done writing
[2025-04-30T19:44:32.931234295Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:52795
[2025-04-30T19:44:32.931243392Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx pkt Short dcid=6dcb970a5a18153c0082f11176af1e9c98b645bf key_phase=false len=1211 pn=2 
[2025-04-30T19:44:32.931249784Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm STREAM id=0 off=0 len=1024 fin=true
[2025-04-30T19:44:32.931253782Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf rx frm CRYPTO off=0 len=162
[2025-04-30T19:44:32.931266556Z TRACE quiche_apps::client] 0.0.0.0:52795: processed 1232 bytes
[2025-04-30T19:44:32.931270203Z TRACE quiche_apps::client] 0.0.0.0:52795: recv() would block
[2025-04-30T19:44:32.931272557Z TRACE quiche_apps::client] done reading
[2025-04-30T19:44:32.931279500Z TRACE quiche_apps::common] received 1024 bytes
[2025-04-30T19:44:32.931282306Z TRACE quiche_apps::common] stream 0 has 1024 bytes (fin? true)
[2025-04-30T19:44:32.931285051Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T19:44:32.931287586Z INFO  quiche_apps::common] 1/1 response(s) received in 77.264276ms, closing...
[2025-04-30T19:44:32.931294098Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1322
[2025-04-30T19:44:32.931297594Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx pkt Short dcid=5504ff8a2353d0546f50 key_phase=false len=10 pn=6 
[2025-04-30T19:44:32.931300089Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T19:44:32.931307533Z TRACE quiche::recovery::congestion::recovery] 6dcb970a5a18153c0082f11176af1e9c98b645bf timer=117.39304ms latest_rtt=33.543602ms srtt=39.304752ms min_rtt=33.543602ms rttvar=14.526057ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=38 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1620, delivered_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, first_sent_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 3, rate_sample: RateSample { delivery_rate: 38005, is_app_limited: true, interval: 33.863327ms, delivered: 1287, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 128, tv_nsec: 528209359 }), send_elapsed: 0ns, ack_elapsed: 33.863327ms, rtt: 33.863327ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 429337, last_update: Instant { tv_sec: 128, tv_nsec: 564079990 }, next_time: Instant { tv_sec: 128, tv_nsec: 562271671 }, max_datagram_size: 1350, last_packet_size: None, iv: 88.509µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T19:44:32.931348260Z TRACE quiche_apps::client] written 38 bytes from 0.0.0.0:52795 to 193.167.100.100:443
[2025-04-30T19:44:32.931357327Z TRACE quiche_apps::client] 0.0.0.0:52795 -> 193.167.100.100:443: done writing
[2025-04-30T19:44:32.932628514Z TRACE quiche_apps::client] got 247 bytes from 193.167.100.100:443 to 0.0.0.0:52795
[2025-04-30T19:44:32.932641198Z TRACE quiche_apps::client] 0.0.0.0:52795: processed 247 bytes
[2025-04-30T19:44:32.932644814Z TRACE quiche_apps::client] 0.0.0.0:52795: recv() would block
[2025-04-30T19:44:32.932647169Z TRACE quiche_apps::client] done reading
[2025-04-30T19:44:32.932650836Z TRACE quiche_apps::client] 0.0.0.0:52795 -> 193.167.100.100:443: done writing
[2025-04-30T19:44:32.962109841Z TRACE quiche_apps::client] got 1372 bytes from 193.167.100.100:443 to 0.0.0.0:52795
[2025-04-30T19:44:32.962140769Z TRACE quiche_apps::client] 0.0.0.0:52795: processed 1372 bytes
[2025-04-30T19:44:32.962146781Z TRACE quiche_apps::client] got 47 bytes from 193.167.100.100:443 to 0.0.0.0:52795
[2025-04-30T19:44:32.962150427Z TRACE quiche_apps::client] 0.0.0.0:52795: processed 47 bytes
[2025-04-30T19:44:32.962155126Z TRACE quiche_apps::client] 0.0.0.0:52795: recv() would block
[2025-04-30T19:44:32.962158372Z TRACE quiche_apps::client] done reading
[2025-04-30T19:44:32.962165726Z TRACE quiche_apps::client] 0.0.0.0:52795 -> 193.167.100.100:443: done writing
[2025-04-30T19:44:33.224475660Z TRACE quiche_apps::client] timed out
[2025-04-30T19:44:33.224508643Z TRACE quiche] 6dcb970a5a18153c0082f11176af1e9c98b645bf draining timeout expired
[2025-04-30T19:44:33.224553267Z TRACE quiche_apps::client] done reading
[2025-04-30T19:44:33.224558066Z INFO  quiche_apps::client] connection closed, recv=6 sent=7 lost=0 retrans=0 sent_bytes=1787 recv_bytes=4842 lost_bytes=0 [local_addr=0.0.0.0:52795 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=6 sent=7 lost=0 retrans=0 rtt=39.304752ms min_rtt=Some(33.543602ms) rttvar=14.526057ms cwnd=13500 sent_bytes=1787 recv_bytes=4842 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=38005]