[2025-04-30T19:46:24.296469117Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T19:46:24.296748694Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:48066 with scid 0f0e51b66387d568a4c980bbc482605dd8895726
[2025-04-30T19:46:24.296796183Z TRACE quiche::tls] 0f0e51b66387d568a4c980bbc482605dd8895726 write message lvl=Initial len=603
[2025-04-30T19:46:24.296811853Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Initial version=1 dcid=73ef3c4bf8fa84d23e792d23705d9080 scid=0f0e51b66387d568a4c980bbc482605dd8895726 len=607 pn=0 
[2025-04-30T19:46:24.296815028Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm CRYPTO off=0 len=603
[2025-04-30T19:46:24.296827983Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=998.926912ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=670 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 239, tv_nsec: 929543552 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 929543552 }, 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: 239, tv_nsec: 929543552 }, next_time: Instant { tv_sec: 239, tv_nsec: 929543552 }, 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:46:24.296921800Z TRACE quiche_apps::client] written 1200
[2025-04-30T19:46:24.332079622Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.332117774Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Initial version=1 dcid=0f0e51b66387d568a4c980bbc482605dd8895726 scid=5737d672a418acbb28aa token= len=122 pn=0 
[2025-04-30T19:46:24.332134395Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T19:46:24.332141689Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 packet newly acked 0
[2025-04-30T19:46:24.332148753Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm CRYPTO off=0 len=96
[2025-04-30T19:46:24.332291471Z TRACE quiche::tls] 0f0e51b66387d568a4c980bbc482605dd8895726 set write secret lvl=Handshake
[2025-04-30T19:46:24.332306470Z TRACE quiche::tls] 0f0e51b66387d568a4c980bbc482605dd8895726 set read secret lvl=Handshake
[2025-04-30T19:46:24.332337578Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Handshake version=1 dcid=0f0e51b66387d568a4c980bbc482605dd8895726 scid=5737d672a418acbb28aa len=203 pn=0 
[2025-04-30T19:46:24.332344702Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm CRYPTO off=0 len=182
[2025-04-30T19:46:24.332377874Z TRACE quiche::tls] 0f0e51b66387d568a4c980bbc482605dd8895726 write message lvl=Handshake len=36
[2025-04-30T19:46:24.332381511Z TRACE quiche::tls] 0f0e51b66387d568a4c980bbc482605dd8895726 set write secret lvl=OneRTT
[2025-04-30T19:46:24.332389025Z TRACE quiche::tls] 0f0e51b66387d568a4c980bbc482605dd8895726 set read secret lvl=OneRTT
[2025-04-30T19:46:24.332407280Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 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(73ef3c4bf8fa84d23e792d23705d9080), 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(5737d672a418acbb28aa), retry_source_connection_id: None, max_datagram_frame_size: Some(0), unknown_params: None }
[2025-04-30T19:46:24.332444520Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0000000000000000000000000000000000000000 key_phase=false len=807 pn=12051831 
[2025-04-30T19:46:24.332450601Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 dropped invalid packet
[2025-04-30T19:46:24.332453868Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1232 bytes
[2025-04-30T19:46:24.332459538Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.332462724Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.332472062Z DEBUG quiche_apps::common] sending HTTP request "GET /afxllxvngj\r\n"
[2025-04-30T19:46:24.332560458Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Initial version=1 dcid=5737d672a418acbb28aa scid=0f0e51b66387d568a4c980bbc482605dd8895726 len=5 pn=1 
[2025-04-30T19:46:24.332564486Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=55 blocks=[0..0] ecn_counts=None
[2025-04-30T19:46:24.332573373Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=105.603796ms latest_rtt=35.355536ms srtt=35.355536ms min_rtt=35.355536ms rttvar=17.677768ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 670, delivered_time: Instant { tv_sec: 239, tv_nsec: 965193072 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 965193072 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 18950, is_app_limited: true, interval: 35.355536ms, delivered: 670, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 929543552 }), send_elapsed: 0ns, ack_elapsed: 35.355536ms, rtt: 35.355536ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 477294, last_update: Instant { tv_sec: 239, tv_nsec: 965193032 }, next_time: Instant { tv_sec: 239, tv_nsec: 965193072 }, 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:46:24.332594061Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Handshake version=1 dcid=5737d672a418acbb28aa scid=0f0e51b66387d568a4c980bbc482605dd8895726 len=45 pn=2 
[2025-04-30T19:46:24.332596907Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=32 blocks=[0..0] ecn_counts=None
[2025-04-30T19:46:24.332599492Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm CRYPTO off=0 len=36
[2025-04-30T19:46:24.332605193Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=106.012817ms latest_rtt=35.355536ms srtt=35.355536ms min_rtt=35.355536ms rttvar=17.677768ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=101 app_limited=true congestion_recovery_start_time=None Rate { delivered: 670, delivered_time: Instant { tv_sec: 239, tv_nsec: 965193072 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 965193072 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 18950, is_app_limited: true, interval: 35.355536ms, delivered: 670, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 929543552 }), send_elapsed: 0ns, ack_elapsed: 35.355536ms, rtt: 35.355536ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 477294, last_update: Instant { tv_sec: 239, tv_nsec: 965339688 }, next_time: Instant { tv_sec: 239, tv_nsec: 965193072 }, max_datagram_size: 1350, last_packet_size: None, iv: 211.61µ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:46:24.332621453Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 dropped epoch 0 state
[2025-04-30T19:46:24.332626222Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13399 out_len=1187 left=1159
[2025-04-30T19:46:24.332637503Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=1159 pn=3 
[2025-04-30T19:46:24.332640058Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[d4, e2, 41, b1, d7, 68, 11, 04, c6, e3, d3, ec, 2b, 7c, f8, fb, b3, 6f, d6, 4c] reset_token=[0a, 75, 7c, 48, 82, 81, d5, 94, 1a, a1, 58, 37, 2d, 4c, 4b, 30]
[2025-04-30T19:46:24.332647052Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[58, a2, 85, 48, bb, 49, e1, cf, 53, b4, e7, 18, f3, d1, 9c, dd, 5f, c2, 93, 15] reset_token=[f6, 85, 59, 16, 91, f7, 22, 72, ba, 81, 2a, 93, 1d, c8, a5, 7a]
[2025-04-30T19:46:24.332652622Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[17, 26, 8d, 34, bd, 92, 07, 50, 9b, e5, ba, 62, 71, da, 7f, f7, 2e, 9c, 3c, cb] reset_token=[90, 45, 62, e2, b9, 54, 4d, ef, 52, 8c, bd, be, 60, dc, 6b, 57]
[2025-04-30T19:46:24.332657892Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[91, 10, 36, db, de, ed, 9c, 57, c5, ed, 61, 55, af, b6, 68, cd, a1, be, d0, 63] reset_token=[a9, 07, 0a, a9, c7, ec, 34, 15, 7b, 0b, 9f, 52, ad, 53, 6b, fc]
[2025-04-30T19:46:24.332663192Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[46, c9, f2, 69, 14, 49, 7b, 62, 25, 05, 20, 05, 8a, 2c, 13, 91, 80, 06, cc, 57] reset_token=[29, 47, ef, 43, c9, 9e, a3, 98, 4e, 18, 1c, f7, 7e, 4f, 3f, 0b]
[2025-04-30T19:46:24.332668572Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[c9, 24, 72, 0a, 34, d7, 61, 05, 30, 1c, 3a, ca, 4f, 99, c3, f7, d3, 1a, 11, f7] reset_token=[40, 5c, c0, 05, 72, 2b, 95, fe, 26, 77, 0e, 0e, 8d, 95, 20, 9b]
[2025-04-30T19:46:24.332673942Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[e9, 72, 9a, 8c, 46, ef, b9, 3d, fa, b9, 6b, 8e, d1, 54, 39, 06, 39, 21, 2c, 56] reset_token=[02, a3, 0e, 55, 3f, 90, df, ac, 70, 18, 85, 46, 7a, b5, e1, 7b]
[2025-04-30T19:46:24.332679262Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T19:46:24.332681817Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm PADDING len=857
[2025-04-30T19:46:24.332690543Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=105.927456ms latest_rtt=35.355536ms srtt=35.355536ms min_rtt=35.355536ms rttvar=17.677768ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1288 app_limited=true congestion_recovery_start_time=None Rate { delivered: 670, delivered_time: Instant { tv_sec: 239, tv_nsec: 965193072 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 965193072 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 18950, is_app_limited: true, interval: 35.355536ms, delivered: 670, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 929543552 }), send_elapsed: 0ns, ack_elapsed: 35.355536ms, rtt: 35.355536ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1187, rate: 477294, last_update: Instant { tv_sec: 239, tv_nsec: 965339688 }, next_time: Instant { tv_sec: 239, tv_nsec: 965551298 }, max_datagram_size: 1350, last_packet_size: Some(1187), 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:46:24.332749645Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.332755416Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.366342499Z TRACE quiche_apps::client] got 1332 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.366366945Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Handshake version=1 dcid=0f0e51b66387d568a4c980bbc482605dd8895726 scid=5737d672a418acbb28aa len=22 pn=1 
[2025-04-30T19:46:24.366380050Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm ACK delay=0 blocks=[2..2] ecn_counts=None
[2025-04-30T19:46:24.366386773Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 packet newly acked 2
[2025-04-30T19:46:24.366410688Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1250 pn=0 
[2025-04-30T19:46:24.366417160Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm ACK delay=0 blocks=[3..3] ecn_counts=None
[2025-04-30T19:46:24.366420126Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 packet newly acked 3
[2025-04-30T19:46:24.366424203Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm HANDSHAKE_DONE
[2025-04-30T19:46:24.366428892Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 dropped epoch 1 state
[2025-04-30T19:46:24.366433320Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[cb, 80, 7e, d0, 11, 04, 51, d6, 26, 4c] reset_token=[d0, 01, 48, 5e, 5e, 68, 17, 04, a2, 5e, 58, bf, 89, cb, 00, 32]
[2025-04-30T19:46:24.366441205Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[b5, 56, 22, ae, b5, 08, 34, 79, 23, 04] reset_token=[54, 69, 96, 61, ed, cd, 01, 4e, f8, a0, 7a, cc, 26, 19, 0c, 18]
[2025-04-30T19:46:24.366446645Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[f8, ca, b2, af, 30, 45, 9f, cf, 5f, f7] reset_token=[8c, 57, be, 7d, 61, ce, 47, a3, f9, 2c, e0, 27, 34, 6d, 77, 7b]
[2025-04-30T19:46:24.366451535Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[ec, 0e, bf, e9, 14, 45, 81, bd, 53, 0a] reset_token=[42, 0c, d0, b3, f7, bc, ad, 2e, 26, 6a, 83, 9f, 09, 5a, 9a, d3]
[2025-04-30T19:46:24.366456795Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[35, 1a, 8e, ee, bd, 75, 07, 59, 5c, 70] reset_token=[8d, 12, 2d, 4b, a4, 63, 6f, 69, fd, db, db, b1, ff, 94, 37, 5b]
[2025-04-30T19:46:24.366461754Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[f3, e8, ed, de, 6a, d1, 2b, 13, db, 12] reset_token=[92, 18, 19, c5, da, 43, a3, 60, b2, 46, d9, aa, 73, d4, f0, f8]
[2025-04-30T19:46:24.366466823Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[e9, 61, 34, 70, f6, 82, 22, 09, 04, 75] reset_token=[e5, 2b, 7d, f0, 67, e2, 73, ed, 8d, bb, 60, 9f, ed, a7, 6b, 41]
[2025-04-30T19:46:24.366474197Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm PADDING len=1017
[2025-04-30T19:46:24.366510386Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1332 bytes
[2025-04-30T19:46:24.366515385Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.366521366Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.366531736Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.366536956Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=4 
[2025-04-30T19:46:24.366539460Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=15 blocks=[0..0] ecn_counts=None
[2025-04-30T19:46:24.366547856Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.366603862Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.366609412Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.367400172Z TRACE quiche_apps::client] got 1332 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.367413237Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1311 pn=1 
[2025-04-30T19:46:24.367421081Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm PING mtu_probe=None
[2025-04-30T19:46:24.367430299Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm PADDING len=1293
[2025-04-30T19:46:24.367438795Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1332 bytes
[2025-04-30T19:46:24.367443323Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.367446359Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.367454484Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.367458692Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=5 
[2025-04-30T19:46:24.367461738Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=5 blocks=[0..1] ecn_counts=None
[2025-04-30T19:46:24.367469743Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.367518074Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.367523214Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.368391380Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.368401148Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1211 pn=2 
[2025-04-30T19:46:24.368408582Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=0 len=1192 fin=false
[2025-04-30T19:46:24.368416437Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1232 bytes
[2025-04-30T19:46:24.368420024Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.368422378Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.368427979Z TRACE quiche_apps::common] received 1192 bytes
[2025-04-30T19:46:24.368431075Z TRACE quiche_apps::common] stream 0 has 1192 bytes (fin? false)
[2025-04-30T19:46:24.368437917Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.368441073Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=6 
[2025-04-30T19:46:24.368443478Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=4 blocks=[0..2] ecn_counts=None
[2025-04-30T19:46:24.368449830Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.368484375Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.368488352Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.369339927Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.369350417Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1211 pn=3 
[2025-04-30T19:46:24.369357249Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=1192 len=1190 fin=false
[2025-04-30T19:46:24.369370595Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1232 bytes
[2025-04-30T19:46:24.369374342Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.369376706Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.369380944Z TRACE quiche_apps::common] received 1190 bytes
[2025-04-30T19:46:24.369383198Z TRACE quiche_apps::common] stream 0 has 1190 bytes (fin? false)
[2025-04-30T19:46:24.369390082Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.369393297Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=7 
[2025-04-30T19:46:24.369395802Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=5 blocks=[0..3] ecn_counts=None
[2025-04-30T19:46:24.369402044Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.369436238Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.369440226Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.370641529Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.370651608Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1211 pn=4 
[2025-04-30T19:46:24.370658241Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=2382 len=1190 fin=false
[2025-04-30T19:46:24.370665565Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1232 bytes
[2025-04-30T19:46:24.370669081Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.370671506Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.370675644Z TRACE quiche_apps::common] received 1190 bytes
[2025-04-30T19:46:24.370677908Z TRACE quiche_apps::common] stream 0 has 1190 bytes (fin? false)
[2025-04-30T19:46:24.370684270Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.370687436Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=8 
[2025-04-30T19:46:24.370689860Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=4 blocks=[0..4] ecn_counts=None
[2025-04-30T19:46:24.370696473Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.370734314Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.370738211Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.372852034Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.372862053Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1211 pn=5 
[2025-04-30T19:46:24.372868585Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=3572 len=1190 fin=false
[2025-04-30T19:46:24.372875859Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1232 bytes
[2025-04-30T19:46:24.372879416Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.372881810Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.372885958Z TRACE quiche_apps::common] received 1190 bytes
[2025-04-30T19:46:24.372888192Z TRACE quiche_apps::common] stream 0 has 1190 bytes (fin? false)
[2025-04-30T19:46:24.372894825Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.372898001Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=9 
[2025-04-30T19:46:24.372900345Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=4 blocks=[0..5] ecn_counts=None
[2025-04-30T19:46:24.372906387Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 9, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.372938798Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.372942825Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.375037953Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.375047681Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1211 pn=6 
[2025-04-30T19:46:24.375054324Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=4762 len=1190 fin=false
[2025-04-30T19:46:24.375061567Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1232 bytes
[2025-04-30T19:46:24.375065184Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.375067609Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.375071687Z TRACE quiche_apps::common] received 1190 bytes
[2025-04-30T19:46:24.375073900Z TRACE quiche_apps::common] stream 0 has 1190 bytes (fin? false)
[2025-04-30T19:46:24.375080313Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.375083418Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=10 
[2025-04-30T19:46:24.375085793Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=4 blocks=[0..6] ecn_counts=None
[2025-04-30T19:46:24.375092035Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 10, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.375124596Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.375128403Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.376051543Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.376061692Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1211 pn=7 
[2025-04-30T19:46:24.376068254Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=5952 len=1190 fin=false
[2025-04-30T19:46:24.376075548Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1232 bytes
[2025-04-30T19:46:24.376079125Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.376081469Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.376085557Z TRACE quiche_apps::common] received 1190 bytes
[2025-04-30T19:46:24.376087791Z TRACE quiche_apps::common] stream 0 has 1190 bytes (fin? false)
[2025-04-30T19:46:24.376094213Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.376097329Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=11 
[2025-04-30T19:46:24.376104663Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=4 blocks=[0..7] ecn_counts=None
[2025-04-30T19:46:24.376110945Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 11, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.376144097Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.376148124Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.378325718Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.378337650Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1211 pn=8 
[2025-04-30T19:46:24.378344543Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=7142 len=1190 fin=false
[2025-04-30T19:46:24.378352148Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1232 bytes
[2025-04-30T19:46:24.378355754Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.378358139Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.378362307Z TRACE quiche_apps::common] received 1190 bytes
[2025-04-30T19:46:24.378364541Z TRACE quiche_apps::common] stream 0 has 1190 bytes (fin? false)
[2025-04-30T19:46:24.378388285Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.378391712Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=12 
[2025-04-30T19:46:24.378394116Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=6 blocks=[0..8] ecn_counts=None
[2025-04-30T19:46:24.378401170Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 12, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.378439181Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.378443339Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.379232516Z TRACE quiche_apps::client] got 1093 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.379247795Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1072 pn=9 
[2025-04-30T19:46:24.379258054Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=8332 len=1051 fin=false
[2025-04-30T19:46:24.379267132Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1093 bytes
[2025-04-30T19:46:24.379271570Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.379274525Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.379279675Z TRACE quiche_apps::common] received 1051 bytes
[2025-04-30T19:46:24.379282430Z TRACE quiche_apps::common] stream 0 has 1051 bytes (fin? false)
[2025-04-30T19:46:24.379290505Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1317
[2025-04-30T19:46:24.379294473Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=5 pn=13 
[2025-04-30T19:46:24.379297469Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm ACK delay=5 blocks=[0..9] ecn_counts=None
[2025-04-30T19:46:24.379305193Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=none latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, first_sent_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, end_of_app_limited: 3, last_sent_packet: 13, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482296, last_update: Instant { tv_sec: 239, tv_nsec: 999314803 }, next_time: Instant { tv_sec: 239, tv_nsec: 999314803 }, 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:46:24.379345729Z TRACE quiche_apps::client] written 33 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.379352472Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.398086824Z TRACE quiche_apps::client] got 1226 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.398112132Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=1205 pn=10 
[2025-04-30T19:46:24.398127471Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm STREAM id=0 off=9383 len=857 fin=true
[2025-04-30T19:46:24.398133793Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm CRYPTO off=0 len=321
[2025-04-30T19:46:24.398171353Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1226 bytes
[2025-04-30T19:46:24.398178226Z TRACE quiche_apps::client] got 83 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.398189959Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx pkt Short dcid=0f0e51b66387d568a4c980bbc482605dd8895726 key_phase=false len=62 pn=11 
[2025-04-30T19:46:24.398198495Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 rx frm NEW_TOKEN len=43
[2025-04-30T19:46:24.398205107Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 83 bytes
[2025-04-30T19:46:24.398209686Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.398235655Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.398244562Z TRACE quiche_apps::common] received 857 bytes
[2025-04-30T19:46:24.398247527Z TRACE quiche_apps::common] stream 0 has 857 bytes (fin? true)
[2025-04-30T19:46:24.398250703Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T19:46:24.398253719Z INFO  quiche_apps::common] 1/1 response(s) received in 101.328203ms, closing...
[2025-04-30T19:46:24.398264018Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1322
[2025-04-30T19:46:24.398269759Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx pkt Short dcid=5737d672a418acbb28aa key_phase=false len=10 pn=14 
[2025-04-30T19:46:24.398272925Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T19:46:24.398284236Z TRACE quiche::recovery::congestion::recovery] 0f0e51b66387d568a4c980bbc482605dd8895726 timer=97.322544ms latest_rtt=33.646675ms srtt=34.988822ms min_rtt=33.646675ms rttvar=10.58968ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=38 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1958, delivered_time: Instant { tv_sec: 240, tv_nsec: 31048317 }, first_sent_time: Instant { tv_sec: 240, tv_nsec: 31048317 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37931, is_app_limited: true, interval: 33.955718ms, delivered: 1288, prior_delivered: 670, prior_time: Some(Instant { tv_sec: 239, tv_nsec: 965193072 }), send_elapsed: 0ns, ack_elapsed: 33.955718ms, rtt: 33.955718ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 38, rate: 482296, last_update: Instant { tv_sec: 240, tv_nsec: 31048317 }, next_time: Instant { tv_sec: 240, tv_nsec: 31048317 }, max_datagram_size: 1350, last_packet_size: Some(38), 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:46:24.398340362Z TRACE quiche_apps::client] written 38 bytes from 0.0.0.0:48066 to 193.167.100.100:443
[2025-04-30T19:46:24.398345381Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.399268340Z TRACE quiche_apps::client] got 1372 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.399277698Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 1372 bytes
[2025-04-30T19:46:24.399281155Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.399283529Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.399287226Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.428899081Z TRACE quiche_apps::client] got 47 bytes from 193.167.100.100:443 to 0.0.0.0:48066
[2025-04-30T19:46:24.428918618Z TRACE quiche_apps::client] 0.0.0.0:48066: processed 47 bytes
[2025-04-30T19:46:24.428923808Z TRACE quiche_apps::client] 0.0.0.0:48066: recv() would block
[2025-04-30T19:46:24.428927054Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.428932815Z TRACE quiche_apps::client] 0.0.0.0:48066 -> 193.167.100.100:443: done writing
[2025-04-30T19:46:24.631163943Z TRACE quiche_apps::client] timed out
[2025-04-30T19:46:24.631184572Z TRACE quiche] 0f0e51b66387d568a4c980bbc482605dd8895726 draining timeout expired
[2025-04-30T19:46:24.631250476Z TRACE quiche_apps::client] done reading
[2025-04-30T19:46:24.631255435Z INFO  quiche_apps::client] connection closed, recv=15 sent=15 lost=0 retrans=0 sent_bytes=2388 recv_bytes=14094 lost_bytes=0 [local_addr=0.0.0.0:48066 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=15 sent=15 lost=0 retrans=0 rtt=34.988822ms min_rtt=Some(33.646675ms) rttvar=10.58968ms cwnd=13500 sent_bytes=2388 recv_bytes=14094 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=37931]