[2025-12-14T03:29:49.878771128Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-12-14T03:29:49.879054966Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:46331 with scid 7b2ac22c951b329a739a614b34a7d8b5d46397cb
[2025-12-14T03:29:49.879096573Z TRACE quiche::tls] 7b2ac22c951b329a739a614b34a7d8b5d46397cb write message lvl=Initial len=266
[2025-12-14T03:29:49.879115178Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Initial version=1 dcid=cc05e267819b52c540f4960c95712682 scid=7b2ac22c951b329a739a614b34a7d8b5d46397cb len=270 pn=0 
[2025-12-14T03:29:49.879119115Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm CRYPTO off=0 len=266
[2025-12-14T03:29:49.879137519Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=998.925091ms 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: 167, tv_nsec: 943919226 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 167, tv_nsec: 943919226 }, next_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, 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-12-14T03:29:49.879227427Z TRACE quiche_apps::client] written 1200
[2025-12-14T03:29:50.879188693Z TRACE quiche_apps::client] timed out
[2025-12-14T03:29:50.879220452Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb loss detection timeout expired
[2025-12-14T03:29:50.879227165Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=997.835034ms 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: 167, tv_nsec: 943919226 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 167, tv_nsec: 943919226 }, next_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, 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-12-14T03:29:50.879252271Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:50.879290793Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Initial version=1 dcid=cc05e267819b52c540f4960c95712682 scid=7b2ac22c951b329a739a614b34a7d8b5d46397cb len=270 pn=1 
[2025-12-14T03:29:50.879295121Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm CRYPTO off=0 len=266
[2025-12-14T03:29:50.879313565Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=1.997954215s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=666 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, 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: 168, tv_nsec: 944124116 }, next_time: Instant { tv_sec: 168, tv_nsec: 944124116 }, 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-12-14T03:29:50.879403884Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:46331 to 193.167.100.100:443
[2025-12-14T03:29:50.879415605Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:51.622033772Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:51.622076261Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx pkt Initial version=1 dcid=7b2ac22c951b329a739a614b34a7d8b5d46397cb scid=7300cc1c7c32eeff token= len=397 pn=0 
[2025-12-14T03:29:51.622095617Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-12-14T03:29:51.622103793Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb packet newly acked 0
[2025-12-14T03:29:51.622112539Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm CRYPTO off=0 len=90
[2025-12-14T03:29:51.622287855Z TRACE quiche::tls] 7b2ac22c951b329a739a614b34a7d8b5d46397cb set write secret lvl=Handshake
[2025-12-14T03:29:51.622304566Z TRACE quiche::tls] 7b2ac22c951b329a739a614b34a7d8b5d46397cb set read secret lvl=Handshake
[2025-12-14T03:29:51.622324724Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm PADDING len=281
[2025-12-14T03:29:51.622360009Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx pkt Handshake version=1 dcid=7b2ac22c951b329a739a614b34a7d8b5d46397cb scid=7300cc1c7c32eeff len=728 pn=0 
[2025-12-14T03:29:51.622370118Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm CRYPTO off=0 len=707
[2025-12-14T03:29:51.622609664Z TRACE quiche::tls] 7b2ac22c951b329a739a614b34a7d8b5d46397cb write message lvl=Handshake len=36
[2025-12-14T03:29:51.622616116Z TRACE quiche::tls] 7b2ac22c951b329a739a614b34a7d8b5d46397cb set write secret lvl=OneRTT
[2025-12-14T03:29:51.622625063Z TRACE quiche::tls] 7b2ac22c951b329a739a614b34a7d8b5d46397cb set read secret lvl=OneRTT
[2025-12-14T03:29:51.622645320Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb 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(cc05e267819b52c540f4960c95712682), max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1500, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 0, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 3, initial_source_connection_id: Some(7300cc1c7c32eeff), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-12-14T03:29:51.622671018Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 1200 bytes
[2025-12-14T03:29:51.622677350Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:51.622681187Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:51.622691656Z DEBUG quiche_apps::common] sending HTTP request "GET /steaming-proud-deoxys\r\n"
[2025-12-14T03:29:51.622775933Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Initial version=1 dcid=7300cc1c7c32eeff scid=7b2ac22c951b329a739a614b34a7d8b5d46397cb len=6 pn=2 
[2025-12-14T03:29:51.622781704Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm ACK delay=87 blocks=[0..0] ecn_counts=None
[2025-12-14T03:29:51.622795730Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=4.485490934s latest_rtt=1.743006388s srtt=1.743006388s min_rtt=1.743006388s rttvar=871.503194ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 169, tv_nsec: 686925614 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.53 Kbps, is_app_limited: true, interval: 1.743006388s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 167, tv_nsec: 943919226 }), send_elapsed: 0ns, ack_elapsed: 1.743006388s, rtt: 1.743006388s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 9681, last_update: Instant { tv_sec: 169, tv_nsec: 687498430 }, next_time: Instant { tv_sec: 169, tv_nsec: 687498470 }, 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-12-14T03:29:51.622825135Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Handshake version=1 dcid=7300cc1c7c32eeff scid=7b2ac22c951b329a739a614b34a7d8b5d46397cb len=45 pn=3 
[2025-12-14T03:29:51.622829523Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm ACK delay=58 blocks=[0..0] ecn_counts=None
[2025-12-14T03:29:51.622833390Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm CRYPTO off=0 len=36
[2025-12-14T03:29:51.622841676Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=4.485445209s latest_rtt=1.743006388s srtt=1.743006388s min_rtt=1.743006388s rttvar=871.503194ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=432 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 169, tv_nsec: 686925614 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.53 Kbps, is_app_limited: true, interval: 1.743006388s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 167, tv_nsec: 943919226 }), send_elapsed: 0ns, ack_elapsed: 1.743006388s, rtt: 1.743006388s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 9681, last_update: Instant { tv_sec: 169, tv_nsec: 687621669 }, next_time: Instant { tv_sec: 169, tv_nsec: 687498470 }, max_datagram_size: 1350, last_packet_size: None, iv: 10.226216ms, 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-12-14T03:29:51.622858587Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb dropped epoch 0 state
[2025-12-14T03:29:51.622874918Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Short dcid=7300cc1c7c32eeff key_phase=false len=1164 pn=4 
[2025-12-14T03:29:51.622878815Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[83, 9b, 4b, 35, dc, 5f, 2a, 8d, d0, b9, 04, e2, 81, 07, 8f, 1e, d3, c8, d3, ec] reset_token=[4d, 5e, 52, c4, cf, 9b, 95, d4, 63, fd, 90, 75, 67, 9b, 91, 1e]
[2025-12-14T03:29:51.622892310Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[7d, a5, f4, 92, 01, 19, 7a, 1f, 5e, 58, cd, c3, b6, 89, 75, 13, 6b, 12, f8, 87] reset_token=[ca, 26, cd, d5, 0b, e5, 43, d5, 9a, 9e, 72, aa, 27, 51, af, ff]
[2025-12-14T03:29:51.622900345Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm STREAM id=0 off=0 len=28 fin=true
[2025-12-14T03:29:51.622904242Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm PADDING len=1051
[2025-12-14T03:29:51.622913409Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=5.228871058s latest_rtt=1.743006388s srtt=1.743006388s min_rtt=1.743006388s rttvar=871.503194ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 169, tv_nsec: 686925614 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.53 Kbps, is_app_limited: true, interval: 1.743006388s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 167, tv_nsec: 943919226 }), send_elapsed: 0ns, ack_elapsed: 1.743006388s, rtt: 1.743006388s } } pacer=Pacer { enabled: true, capacity: 13500, used: 1190, rate: 9681, last_update: Instant { tv_sec: 169, tv_nsec: 687621669 }, next_time: Instant { tv_sec: 169, tv_nsec: 697847885 }, max_datagram_size: 1350, last_packet_size: Some(1190), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-12-14T03:29:51.622977378Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:46331 to 193.167.100.100:443
[2025-12-14T03:29:51.622988148Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:51.825421992Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:51.825456717Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb dropped invalid packet
[2025-12-14T03:29:51.825461766Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 1200 bytes
[2025-12-14T03:29:51.825466645Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:51.825469871Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:51.825478598Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:52.022882922Z TRACE quiche_apps::client] got 765 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:52.022914050Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx pkt Handshake version=1 dcid=7b2ac22c951b329a739a614b34a7d8b5d46397cb scid=7300cc1c7c32eeff len=728 pn=1 
[2025-12-14T03:29:52.022931132Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm CRYPTO off=0 len=707
[2025-12-14T03:29:52.022956850Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 765 bytes
[2025-12-14T03:29:52.022962801Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:52.022966638Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:52.022983159Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Handshake version=1 dcid=7300cc1c7c32eeff scid=7b2ac22c951b329a739a614b34a7d8b5d46397cb len=5 pn=5 
[2025-12-14T03:29:52.022987597Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm ACK delay=9 blocks=[0..1] ecn_counts=None
[2025-12-14T03:29:52.023001733Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=4.828782163s latest_rtt=1.743006388s srtt=1.743006388s min_rtt=1.743006388s rttvar=871.503194ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 169, tv_nsec: 686925614 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.53 Kbps, is_app_limited: true, interval: 1.743006388s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 167, tv_nsec: 943919226 }), send_elapsed: 0ns, ack_elapsed: 1.743006388s, rtt: 1.743006388s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 9681, last_update: Instant { tv_sec: 170, tv_nsec: 87828905 }, next_time: Instant { tv_sec: 169, tv_nsec: 697847885 }, max_datagram_size: 1350, last_packet_size: None, iv: 122.921186ms, 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-12-14T03:29:52.023102401Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:46331 to 193.167.100.100:443
[2025-12-14T03:29:52.023112048Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:52.023123400Z TRACE quiche_apps::client] got 56 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:52.023129110Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx pkt Handshake version=1 dcid=7b2ac22c951b329a739a614b34a7d8b5d46397cb scid=7300cc1c7c32eeff len=20 pn=2 
[2025-12-14T03:29:52.023136835Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm PING mtu_probe=None
[2025-12-14T03:29:52.023141022Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm PADDING len=2
[2025-12-14T03:29:52.023183351Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 56 bytes
[2025-12-14T03:29:52.023192048Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:52.023197528Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:52.023213337Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Handshake version=1 dcid=7300cc1c7c32eeff scid=7b2ac22c951b329a739a614b34a7d8b5d46397cb len=5 pn=6 
[2025-12-14T03:29:52.023219859Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm ACK delay=10 blocks=[0..2] ecn_counts=None
[2025-12-14T03:29:52.023234727Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=4.828548708s latest_rtt=1.743006388s srtt=1.743006388s min_rtt=1.743006388s rttvar=871.503194ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 169, tv_nsec: 686925614 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.53 Kbps, is_app_limited: true, interval: 1.743006388s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 167, tv_nsec: 943919226 }), send_elapsed: 0ns, ack_elapsed: 1.743006388s, rtt: 1.743006388s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 9681, last_update: Instant { tv_sec: 170, tv_nsec: 87828905 }, next_time: Instant { tv_sec: 170, tv_nsec: 210980930 }, 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-12-14T03:29:52.023278528Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:46331 to 193.167.100.100:443
[2025-12-14T03:29:52.023284680Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:52.387789173Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:52.387827274Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb dropped invalid packet
[2025-12-14T03:29:52.387847081Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 1200 bytes
[2025-12-14T03:29:52.387854725Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:52.387859514Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:52.387871326Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:52.625956905Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:52.625989786Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb dropped invalid packet
[2025-12-14T03:29:52.625995677Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 1200 bytes
[2025-12-14T03:29:52.626001577Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:52.626005395Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:52.626015904Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:52.626947388Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:52.626963598Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb dropped invalid packet
[2025-12-14T03:29:52.626968527Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 1200 bytes
[2025-12-14T03:29:52.626975450Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:52.626980018Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:52.626988324Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:53.134999050Z TRACE quiche_apps::client] got 765 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:53.135040266Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx pkt Handshake version=1 dcid=7b2ac22c951b329a739a614b34a7d8b5d46397cb scid=7300cc1c7c32eeff len=728 pn=3 
[2025-12-14T03:29:53.135058790Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm CRYPTO off=0 len=707
[2025-12-14T03:29:53.135094126Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 765 bytes
[2025-12-14T03:29:53.135100598Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:53.135104776Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:53.135123501Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Handshake version=1 dcid=7300cc1c7c32eeff scid=7b2ac22c951b329a739a614b34a7d8b5d46397cb len=5 pn=7 
[2025-12-14T03:29:53.135128420Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm ACK delay=10 blocks=[0..3] ecn_counts=None
[2025-12-14T03:29:53.135143368Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=3.716607948s latest_rtt=1.743006388s srtt=1.743006388s min_rtt=1.743006388s rttvar=871.503194ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 169, tv_nsec: 686925614 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.53 Kbps, is_app_limited: true, interval: 1.743006388s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 167, tv_nsec: 943919226 }), send_elapsed: 0ns, ack_elapsed: 1.743006388s, rtt: 1.743006388s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 9681, last_update: Instant { tv_sec: 170, tv_nsec: 87828905 }, next_time: Instant { tv_sec: 170, tv_nsec: 210980930 }, 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-12-14T03:29:53.135247762Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:46331 to 193.167.100.100:443
[2025-12-14T03:29:53.135255326Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:53.152564147Z TRACE quiche_apps::client] got 224 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:53.152580969Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx pkt Short dcid=7b2ac22c951b329a739a614b34a7d8b5d46397cb key_phase=false len=203 pn=0 
[2025-12-14T03:29:53.152591358Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm HANDSHAKE_DONE
[2025-12-14T03:29:53.152603771Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb dropped epoch 1 state
[2025-12-14T03:29:53.152611375Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e7, c4, 77, 79, dd, 74, d8, 5e] reset_token=[35, 74, f2, 79, f5, dd, df, ba, 84, ab, df, ab, 67, 06, fc, e6]
[2025-12-14T03:29:53.152621103Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[72, 34, 06, f4, da, c6, 0a, 41] reset_token=[a5, 15, d4, 62, c8, fe, 2d, bc, 81, 36, 22, ca, 22, 3f, 91, dd]
[2025-12-14T03:29:53.152628337Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[5c, b9, f1, a4, 3b, 6c, 2e, a4] reset_token=[0b, ae, 30, 2b, fe, 28, c5, 8d, 97, 89, 23, 9b, 25, 30, da, 3b]
[2025-12-14T03:29:53.152635610Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[2a, 02, 55, 53, a2, 45, 97, 76] reset_token=[cd, ed, b7, d2, f8, 65, bb, 95, a8, 64, 9c, bc, 9e, f2, 55, 4f]
[2025-12-14T03:29:53.152642904Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[d9, 66, 34, 07, e1, 1b, c9, 2a] reset_token=[27, cd, b0, 92, 84, 6c, d1, 6a, 97, 86, f8, ba, 0e, 06, 43, e0]
[2025-12-14T03:29:53.152649025Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm CRYPTO off=0 len=42
[2025-12-14T03:29:53.152714247Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 224 bytes
[2025-12-14T03:29:53.152720719Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:53.152724456Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:53.152735777Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Short dcid=7300cc1c7c32eeff key_phase=false len=5 pn=8 
[2025-12-14T03:29:53.152739985Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm ACK delay=19 blocks=[0..0] ecn_counts=None
[2025-12-14T03:29:53.152749552Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=3.724034734s latest_rtt=1.743006388s srtt=1.743006388s min_rtt=1.743006388s rttvar=871.503194ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1190 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 169, tv_nsec: 686925614 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.53 Kbps, is_app_limited: true, interval: 1.743006388s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 167, tv_nsec: 943919226 }), send_elapsed: 0ns, ack_elapsed: 1.743006388s, rtt: 1.743006388s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 9681, last_update: Instant { tv_sec: 170, tv_nsec: 87828905 }, next_time: Instant { tv_sec: 170, tv_nsec: 210980930 }, 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-12-14T03:29:53.152798664Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:46331 to 193.167.100.100:443
[2025-12-14T03:29:53.152804244Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:53.160567541Z TRACE quiche_apps::client] got 1070 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:53.160592678Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx pkt Short dcid=7b2ac22c951b329a739a614b34a7d8b5d46397cb key_phase=false len=1049 pn=1 
[2025-12-14T03:29:53.160604019Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm STREAM id=0 off=0 len=1024 fin=true
[2025-12-14T03:29:53.160610371Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb rx frm MAX_STREAMS type=bidi max=101
[2025-12-14T03:29:53.160634946Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 1070 bytes
[2025-12-14T03:29:53.160640817Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:53.160644784Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:53.160651958Z TRACE quiche_apps::common] received 1024 bytes
[2025-12-14T03:29:53.160656316Z TRACE quiche_apps::common] stream 0 has 1024 bytes (fin? true)
[2025-12-14T03:29:53.160660303Z DEBUG quiche_apps::common] 1/1 responses received
[2025-12-14T03:29:53.160664151Z INFO  quiche_apps::common] 1/1 response(s) received in 3.281431754s, closing...
[2025-12-14T03:29:53.160675271Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx pkt Short dcid=7300cc1c7c32eeff key_phase=false len=10 pn=9 
[2025-12-14T03:29:53.160679720Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-12-14T03:29:53.160690560Z TRACE quiche::recovery::congestion::recovery] 7b2ac22c951b329a739a614b34a7d8b5d46397cb timer=5.253997995s latest_rtt=1.743006388s srtt=1.743006388s min_rtt=1.743006388s rttvar=871.503194ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1226 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 169, tv_nsec: 686925614 }, first_sent_time: Instant { tv_sec: 167, tv_nsec: 943919226 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.53 Kbps, is_app_limited: true, interval: 1.743006388s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 167, tv_nsec: 943919226 }), send_elapsed: 0ns, ack_elapsed: 1.743006388s, rtt: 1.743006388s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 9681, last_update: Instant { tv_sec: 171, tv_nsec: 225526097 }, next_time: Instant { tv_sec: 170, tv_nsec: 210980930 }, max_datagram_size: 1350, last_packet_size: None, iv: 3.718624ms, 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-12-14T03:29:53.160743288Z TRACE quiche_apps::client] written 36 bytes from 0.0.0.0:46331 to 193.167.100.100:443
[2025-12-14T03:29:53.160749910Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:53.177692960Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:53.177708499Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 44 bytes
[2025-12-14T03:29:53.177714300Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:53.177718187Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:53.177726933Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:29:54.667133902Z TRACE quiche_apps::client] got 42 bytes from 193.167.100.100:443 to 0.0.0.0:46331
[2025-12-14T03:29:54.667187782Z TRACE quiche_apps::client] 0.0.0.0:46331: processed 42 bytes
[2025-12-14T03:29:54.667196539Z TRACE quiche_apps::client] 0.0.0.0:46331: recv() would block
[2025-12-14T03:29:54.667202099Z TRACE quiche_apps::client] done reading
[2025-12-14T03:29:54.667217838Z TRACE quiche_apps::client] 0.0.0.0:46331 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:08.862035006Z TRACE quiche_apps::client] timed out
[2025-12-14T03:30:08.862073788Z TRACE quiche] 7b2ac22c951b329a739a614b34a7d8b5d46397cb draining timeout expired
[2025-12-14T03:30:08.862123861Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:08.862131616Z INFO  quiche_apps::client] connection closed, recv=7 sent=10 lost=0 retrans=1 sent_bytes=2260 recv_bytes=4080 lost_bytes=0 [local_addr=0.0.0.0:46331 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=7 sent=10 lost=0 retrans=1 rtt=1.743006388s min_rtt=Some(1.743006388s) rttvar=871.503194ms cwnd=13500 sent_bytes=2260 recv_bytes=4080 lost_bytes=0 stream_retrans_bytes=266 pmtu=1350 delivery_rate=191]