[2025-12-14T03:30:52.590029261Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-12-14T03:30:52.590335591Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:38974 with scid 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4
[2025-12-14T03:30:52.590379322Z TRACE quiche::tls] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 write message lvl=Initial len=266
[2025-12-14T03:30:52.590396384Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Initial version=1 dcid=7293ae9bbf59cb09f1b2c2390d6b394e scid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 len=270 pn=0 
[2025-12-14T03:30:52.590400131Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm CRYPTO off=0 len=266
[2025-12-14T03:30:52.590414608Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=998.930601ms 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: 230, tv_nsec: 655201815 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 655201815 }, 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: 230, tv_nsec: 655201815 }, next_time: Instant { tv_sec: 230, tv_nsec: 655201815 }, 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:30:52.590470282Z TRACE quiche_apps::client] written 1200
[2025-12-14T03:30:52.739789193Z TRACE quiche_apps::client] got 88 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.739822274Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Retry version=1 dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 scid=25ac18287a486ffd token=0b6c5f766fae153ba5d8dd36fe145e3a91e5dc1e1a0ee7c92bb3caab7ad558b76626322594
[2025-12-14T03:30:52.739849986Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 dropped epoch 0 state
[2025-12-14T03:30:52.739862038Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 88 bytes
[2025-12-14T03:30:52.739866857Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.739869973Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.739913284Z TRACE quiche::tls] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 write message lvl=Initial len=266
[2025-12-14T03:30:52.739931758Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Initial version=1 dcid=25ac18287a486ffd scid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 token=0b6c5f766fae153ba5d8dd36fe145e3a91e5dc1e1a0ee7c92bb3caab7ad558b76626322594 len=270 pn=1 
[2025-12-14T03:30:52.739935926Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm CRYPTO off=0 len=266
[2025-12-14T03:30:52.739952657Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=998.925912ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=362 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 230, tv_nsec: 804735225 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 804735225 }, 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: 230, tv_nsec: 804735225 }, next_time: Instant { tv_sec: 230, tv_nsec: 804735225 }, 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:30:52.740017838Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.740024661Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.889687376Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.889728863Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Initial version=1 dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 scid=25ac18287a486ffd token= len=389 pn=0 
[2025-12-14T03:30:52.889750123Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm ACK delay=0 blocks=[1..1] ecn_counts=None
[2025-12-14T03:30:52.889760242Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 packet newly acked 1
[2025-12-14T03:30:52.889769419Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm CRYPTO off=0 len=90
[2025-12-14T03:30:52.889913968Z TRACE quiche::tls] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 set write secret lvl=Handshake
[2025-12-14T03:30:52.889930088Z TRACE quiche::tls] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 set read secret lvl=Handshake
[2025-12-14T03:30:52.889945206Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm PADDING len=273
[2025-12-14T03:30:52.889979229Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Handshake version=1 dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 scid=25ac18287a486ffd len=736 pn=0 
[2025-12-14T03:30:52.889988837Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm CRYPTO off=0 len=715
[2025-12-14T03:30:52.890288074Z TRACE quiche::tls] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 write message lvl=Handshake len=36
[2025-12-14T03:30:52.890297922Z TRACE quiche::tls] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 set write secret lvl=OneRTT
[2025-12-14T03:30:52.890308492Z TRACE quiche::tls] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 set read secret lvl=OneRTT
[2025-12-14T03:30:52.890332437Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 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(7293ae9bbf59cb09f1b2c2390d6b394e), 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(25ac18287a486ffd), retry_source_connection_id: Some(25ac18287a486ffd), max_datagram_frame_size: None, unknown_params: None }
[2025-12-14T03:30:52.890364767Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1200 bytes
[2025-12-14T03:30:52.890372541Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.890377270Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.890388471Z DEBUG quiche_apps::common] sending HTTP request "GET /gigantic-sour-mesprit\r\n"
[2025-12-14T03:30:52.890481414Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Initial version=1 dcid=25ac18287a486ffd scid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 token=0b6c5f766fae153ba5d8dd36fe145e3a91e5dc1e1a0ee7c92bb3caab7ad558b76626322594 len=6 pn=2 
[2025-12-14T03:30:52.890488748Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=94 blocks=[0..0] ecn_counts=None
[2025-12-14T03:30:52.890504667Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=448.744541ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 230, tv_nsec: 955185176 }, next_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, 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:30:52.890549361Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Handshake version=1 dcid=25ac18287a486ffd scid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 len=46 pn=3 
[2025-12-14T03:30:52.890555191Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=71 blocks=[0..0] ecn_counts=None
[2025-12-14T03:30:52.890560301Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm CRYPTO off=0 len=36
[2025-12-14T03:30:52.890570490Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=449.423916ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=100 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 230, tv_nsec: 955322862 }, next_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, max_datagram_size: 1350, last_packet_size: None, iv: 887.958µ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-12-14T03:30:52.890593943Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 dropped epoch 0 state
[2025-12-14T03:30:52.890614542Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=1126 pn=4 
[2025-12-14T03:30:52.890619922Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[5c, 33, 29, 03, a3, 36, 39, 77, 9a, 85, 46, 8a, d1, 12, 73, 6c, 07, 32, aa, e0] reset_token=[1e, 30, f7, 34, b1, 7a, 17, 89, 08, 05, 27, 62, 3d, 05, d0, 8b]
[2025-12-14T03:30:52.890632205Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[70, 2b, ba, ea, 9a, 48, 60, 59, 8e, 77, fb, 5a, 79, 52, be, 4f, df, 22, 21, 29] reset_token=[68, ec, d7, a9, aa, 9a, e1, b1, 6a, 76, 85, 7c, 05, ec, e9, 46]
[2025-12-14T03:30:52.890642193Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm STREAM id=0 off=0 len=28 fin=true
[2025-12-14T03:30:52.890647143Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm PADDING len=1013
[2025-12-14T03:30:52.890663653Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=449.330672ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1252 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1152, rate: 112618, last_update: Instant { tv_sec: 230, tv_nsec: 955322862 }, next_time: Instant { tv_sec: 230, tv_nsec: 956210820 }, max_datagram_size: 1350, last_packet_size: Some(1152), 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:30:52.890723134Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.890731569Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.940411534Z TRACE quiche_apps::client] got 41 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.940451208Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=20 pn=0 
[2025-12-14T03:30:52.940469702Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm HANDSHAKE_DONE
[2025-12-14T03:30:52.940480452Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 dropped epoch 1 state
[2025-12-14T03:30:52.940486804Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm PADDING len=2
[2025-12-14T03:30:52.940507262Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 41 bytes
[2025-12-14T03:30:52.940514936Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.940520096Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.940543530Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=5 
[2025-12-14T03:30:52.940550202Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=11 blocks=[0..0] ecn_counts=None
[2025-12-14T03:30:52.940567574Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=424.42583ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 230, tv_nsec: 956210820 }, max_datagram_size: 1350, last_packet_size: None, iv: 10.229271ms, 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:30:52.941559858Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.941576549Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.944039443Z TRACE quiche_apps::client] got 223 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.944054872Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=202 pn=1 
[2025-12-14T03:30:52.944068277Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[95, 51, 8c, b2, 7c, 9c, f6, 99] reset_token=[82, 88, a8, 0e, d5, a3, 5e, d8, 46, 87, 7e, 00, 46, 84, fd, 9d]
[2025-12-14T03:30:52.944081451Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[04, 53, 8b, b6, be, 1e, 09, 87] reset_token=[e7, d9, 13, e0, fc, 86, df, b9, 13, df, 9a, c7, 88, 64, 1c, a5]
[2025-12-14T03:30:52.944090238Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[6a, cf, 2a, 55, e9, 31, ba, 7e] reset_token=[62, f4, 69, 2a, d7, 69, 81, e8, f8, 8a, 47, 04, 87, d0, 42, 11]
[2025-12-14T03:30:52.944099024Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[0c, ed, e1, a3, ce, 82, a6, ad] reset_token=[c0, 31, f9, 2d, 45, 52, c8, 4f, 71, d8, c0, d0, dd, 4c, 53, 63]
[2025-12-14T03:30:52.944108782Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[c4, a2, cc, 60, 63, 80, e6, 91] reset_token=[fa, b3, a7, eb, e5, 28, e8, 40, 09, f4, 4d, 15, 22, 0b, c8, b6]
[2025-12-14T03:30:52.944116537Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm CRYPTO off=0 len=42
[2025-12-14T03:30:52.944175116Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 223 bytes
[2025-12-14T03:30:52.944186146Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.944190695Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.944201465Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=6 
[2025-12-14T03:30:52.944206464Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=18 blocks=[0..1] ecn_counts=None
[2025-12-14T03:30:52.944216964Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=420.777292ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.944270012Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.944277556Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.954047430Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.954071866Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=1231 pn=2 
[2025-12-14T03:30:52.954084619Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=0 len=1209 fin=false
[2025-12-14T03:30:52.954096962Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1252 bytes
[2025-12-14T03:30:52.954103344Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.954107882Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.954115407Z TRACE quiche_apps::common] received 1209 bytes
[2025-12-14T03:30:52.954119905Z TRACE quiche_apps::common] stream 0 has 1209 bytes (fin? false)
[2025-12-14T03:30:52.954132098Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=7 
[2025-12-14T03:30:52.954137437Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=7 blocks=[0..2] ecn_counts=None
[2025-12-14T03:30:52.954166371Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=410.827493ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.954767029Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.954779022Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.955312044Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.955327052Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=1231 pn=3 
[2025-12-14T03:30:52.955338252Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=1209 len=1208 fin=false
[2025-12-14T03:30:52.955349814Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1252 bytes
[2025-12-14T03:30:52.955355825Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.955360133Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.955395710Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:30:52.955400438Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:30:52.955412200Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=8 
[2025-12-14T03:30:52.955417159Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=10 blocks=[0..3] ecn_counts=None
[2025-12-14T03:30:52.955428511Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=409.565755ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.955479365Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.955486949Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.956200810Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.956217030Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=1231 pn=4 
[2025-12-14T03:30:52.956228341Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=2417 len=1208 fin=false
[2025-12-14T03:30:52.956240284Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1252 bytes
[2025-12-14T03:30:52.956246916Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.956251424Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.956258357Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:30:52.956262645Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:30:52.956274177Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=9 
[2025-12-14T03:30:52.956279186Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=7 blocks=[0..4] ecn_counts=None
[2025-12-14T03:30:52.956289395Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=408.7049ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.956338025Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.956345039Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.957684289Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.957707021Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=1231 pn=5 
[2025-12-14T03:30:52.957718523Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=3625 len=1208 fin=false
[2025-12-14T03:30:52.957729974Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1252 bytes
[2025-12-14T03:30:52.957736356Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.957740894Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.957748428Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:30:52.957753007Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:30:52.957764668Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=10 
[2025-12-14T03:30:52.957769838Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=7 blocks=[0..5] ecn_counts=None
[2025-12-14T03:30:52.957780798Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=407.213507ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 10, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.957831443Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.957839157Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.958696593Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.958710178Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=1231 pn=6 
[2025-12-14T03:30:52.958720618Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=4833 len=1208 fin=false
[2025-12-14T03:30:52.958731067Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1252 bytes
[2025-12-14T03:30:52.958737419Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.958742188Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.958748219Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:30:52.958752547Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:30:52.958763538Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=11 
[2025-12-14T03:30:52.958768627Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=6 blocks=[0..6] ecn_counts=None
[2025-12-14T03:30:52.958778455Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=406.21573ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 11, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.958832847Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.958840731Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.959739700Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.959756551Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=1231 pn=7 
[2025-12-14T03:30:52.959778231Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=6041 len=1208 fin=false
[2025-12-14T03:30:52.959791987Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1252 bytes
[2025-12-14T03:30:52.959799190Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.959803919Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.959811383Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:30:52.959816362Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:30:52.959829507Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=12 
[2025-12-14T03:30:52.959835338Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=9 blocks=[0..7] ecn_counts=None
[2025-12-14T03:30:52.959848041Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=405.145904ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 12, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.959901821Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.959910728Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.960749234Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.960764722Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=1231 pn=8 
[2025-12-14T03:30:52.960781995Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=7249 len=1208 fin=false
[2025-12-14T03:30:52.960793536Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1252 bytes
[2025-12-14T03:30:52.960799757Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.960804136Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.960810828Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:30:52.960814916Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:30:52.960844791Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=13 
[2025-12-14T03:30:52.960850181Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=9 blocks=[0..8] ecn_counts=None
[2025-12-14T03:30:52.960860982Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=404.133284ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 13, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.960912057Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.960919971Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.961764503Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.961776996Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=1231 pn=9 
[2025-12-14T03:30:52.961788077Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=8457 len=1208 fin=false
[2025-12-14T03:30:52.961800059Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 1252 bytes
[2025-12-14T03:30:52.961807273Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.961812172Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.961819355Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:30:52.961823854Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:30:52.961835285Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=5 pn=14 
[2025-12-14T03:30:52.961841196Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm ACK delay=7 blocks=[0..9] ecn_counts=None
[2025-12-14T03:30:52.961851715Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=403.14248ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1152 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 3, last_sent_packet: 14, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 5384366 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, 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:30:52.961905656Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.961913120Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.962275895Z TRACE quiche_apps::client] got 622 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.962287657Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx pkt Short dcid=2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 key_phase=false len=601 pn=10 
[2025-12-14T03:30:52.962296894Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm STREAM id=0 off=9665 len=575 fin=true
[2025-12-14T03:30:52.962302454Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 rx frm MAX_STREAMS type=bidi max=101
[2025-12-14T03:30:52.962312223Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 622 bytes
[2025-12-14T03:30:52.962318204Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.962322482Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.962328713Z TRACE quiche_apps::common] received 575 bytes
[2025-12-14T03:30:52.962332590Z TRACE quiche_apps::common] stream 0 has 575 bytes (fin? true)
[2025-12-14T03:30:52.962336748Z DEBUG quiche_apps::common] 1/1 responses received
[2025-12-14T03:30:52.962340956Z INFO  quiche_apps::common] 1/1 response(s) received in 371.866266ms, closing...
[2025-12-14T03:30:52.962350895Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx pkt Short dcid=25ac18287a486ffd key_phase=false len=10 pn=15 
[2025-12-14T03:30:52.962355593Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-12-14T03:30:52.962366513Z TRACE quiche::recovery::congestion::recovery] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 timer=474.507892ms latest_rtt=149.84274ms srtt=149.84274ms min_rtt=149.84274ms rttvar=74.92137ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1188 app_limited=true congestion_recovery_start_time=None Rate { delivered: 362, delivered_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, first_sent_time: Instant { tv_sec: 230, tv_nsec: 955185216 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 1, rate_sample: RateSample { bandwidth: 19.32 Kbps, is_app_limited: true, interval: 149.84274ms, delivered: 362, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 230, tv_nsec: 804735225 }), send_elapsed: 0ns, ack_elapsed: 149.84274ms, rtt: 149.84274ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 112618, last_update: Instant { tv_sec: 231, tv_nsec: 27202992 }, next_time: Instant { tv_sec: 231, tv_nsec: 19281240 }, max_datagram_size: 1350, last_packet_size: None, iv: 319.665µ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-12-14T03:30:52.962412369Z TRACE quiche_apps::client] written 36 bytes from 0.0.0.0:38974 to 193.167.100.100:443
[2025-12-14T03:30:52.962418681Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:52.970011304Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:52.970032313Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 44 bytes
[2025-12-14T03:30:52.970040669Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:52.970046550Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:52.970055827Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:53.003143152Z TRACE quiche_apps::client] got 42 bytes from 193.167.100.100:443 to 0.0.0.0:38974
[2025-12-14T03:30:53.003196512Z TRACE quiche_apps::client] 0.0.0.0:38974: processed 42 bytes
[2025-12-14T03:30:53.003203434Z TRACE quiche_apps::client] 0.0.0.0:38974: recv() would block
[2025-12-14T03:30:53.003207462Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:53.003215457Z TRACE quiche_apps::client] 0.0.0.0:38974 -> 193.167.100.100:443: done writing
[2025-12-14T03:30:54.312603075Z TRACE quiche_apps::client] timed out
[2025-12-14T03:30:54.312638020Z TRACE quiche] 2c4c9c02ef8cb0bf870563803fe0a392fd8f74e4 draining timeout expired
[2025-12-14T03:30:54.312691029Z TRACE quiche_apps::client] done reading
[2025-12-14T03:30:54.312698152Z INFO  quiche_apps::client] connection closed, recv=13 sent=16 lost=0 retrans=0 sent_bytes=2391 recv_bytes=12102 lost_bytes=0 [local_addr=0.0.0.0:38974 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=13 sent=16 lost=0 retrans=0 rtt=149.84274ms min_rtt=Some(149.84274ms) rttvar=74.92137ms cwnd=13500 sent_bytes=2391 recv_bytes=12102 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=2415]