[2025-04-30T09:10:57.321014608Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T09:10:57.321311438Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:59876 with scid 7e8bf38231e73c92e203c62c91b59bdfbf3926e5
[2025-04-30T09:10:57.321354098Z TRACE quiche::tls] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 write message lvl=Initial len=266
[2025-04-30T09:10:57.321373284Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Initial version=1 dcid=b536721e62d964a55905768a3f2601f8 scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 len=270 pn=0 
[2025-04-30T09:10:57.321376400Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm CRYPTO off=0 len=266
[2025-04-30T09:10:57.321390497Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=998.929728ms 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: 266, tv_nsec: 23788002 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 23788002 }, 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: 266, tv_nsec: 23788002 }, next_time: Instant { tv_sec: 266, tv_nsec: 23788002 }, 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-30T09:10:57.321456601Z TRACE quiche_apps::client] written 1200
[2025-04-30T09:10:57.358033208Z TRACE quiche_apps::client] got 101 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.358060569Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Retry version=1 dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 scid=1a31c35cb507bb280fb5da536c4c24119f73795c token=71756963686500000000000000000000ffffc1a70064b536721e62d964a55905768a3f2601f8
[2025-04-30T09:10:57.358090576Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 dropped epoch 0 state
[2025-04-30T09:10:57.358102138Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 101 bytes
[2025-04-30T09:10:57.358107268Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.358110414Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.358158294Z TRACE quiche::tls] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 write message lvl=Initial len=266
[2025-04-30T09:10:57.358177019Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Initial version=1 dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 token=71756963686500000000000000000000ffffc1a70064b536721e62d964a55905768a3f2601f8 len=270 pn=1 
[2025-04-30T09:10:57.358181448Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm CRYPTO off=0 len=266
[2025-04-30T09:10:57.358195674Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=998.923485ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=375 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 266, tv_nsec: 60586937 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 60586937 }, end_of_app_limited: 1, last_sent_packet: 1, 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: 266, tv_nsec: 60586937 }, next_time: Instant { tv_sec: 266, tv_nsec: 60586937 }, 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-30T09:10:57.358264734Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.358271457Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.391290343Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.391310561Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Initial version=1 dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 scid=1a31c35cb507bb280fb5da536c4c24119f73795c token= len=116 pn=0 
[2025-04-30T09:10:57.391324327Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm ACK delay=44 blocks=[1..1] ecn_counts=None
[2025-04-30T09:10:57.391330118Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 packet newly acked 1
[2025-04-30T09:10:57.391336470Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm CRYPTO off=0 len=90
[2025-04-30T09:10:57.391427401Z TRACE quiche::tls] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 set write secret lvl=Handshake
[2025-04-30T09:10:57.391436919Z TRACE quiche::tls] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 set read secret lvl=Handshake
[2025-04-30T09:10:57.391466194Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Handshake version=1 dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 scid=1a31c35cb507bb280fb5da536c4c24119f73795c len=741 pn=1 
[2025-04-30T09:10:57.391471985Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm CRYPTO off=0 len=720
[2025-04-30T09:10:57.391712309Z TRACE quiche::tls] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 write message lvl=Handshake len=36
[2025-04-30T09:10:57.391717608Z TRACE quiche::tls] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 set write secret lvl=OneRTT
[2025-04-30T09:10:57.391725994Z TRACE quiche::tls] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 set read secret lvl=OneRTT
[2025-04-30T09:10:57.391744549Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(b536721e62d964a55905768a3f2601f8), max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1350, 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: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(1a31c35cb507bb280fb5da536c4c24119f73795c), retry_source_connection_id: Some(1a31c35cb507bb280fb5da536c4c24119f73795c), max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T09:10:57.391770408Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=0000000000000000000000000000000000000000 key_phase=false len=223 pn=11860289 
[2025-04-30T09:10:57.391776319Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 dropped invalid packet
[2025-04-30T09:10:57.391779596Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1200 bytes
[2025-04-30T09:10:57.391785016Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.391788382Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.391804953Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm SETTINGS stream=2 len=18
[2025-04-30T09:10:57.391820422Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 open GREASE stream 14
[2025-04-30T09:10:57.391830622Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm GREASE stream=0 len=0
[2025-04-30T09:10:57.391844017Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm GREASE stream=0 len=18
[2025-04-30T09:10:57.391852914Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm HEADERS stream=0 len=30 fin=true
[2025-04-30T09:10:57.391860538Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /wzyzjakicp", "user-agent: quiche"]
[2025-04-30T09:10:57.391943685Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Initial version=1 dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 token=71756963686500000000000000000000ffffc1a70064b536721e62d964a55905768a3f2601f8 len=6 pn=2 
[2025-04-30T09:10:57.391948474Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=79 blocks=[0..0] ecn_counts=None
[2025-04-30T09:10:57.391958894Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=98.907166ms latest_rtt=33.186703ms srtt=33.186703ms min_rtt=33.186703ms rttvar=16.593351ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 375, delivered_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11299, is_app_limited: true, interval: 33.186703ms, delivered: 375, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 60586937 }), send_elapsed: 0ns, ack_elapsed: 33.186703ms, rtt: 33.186703ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 508486, last_update: Instant { tv_sec: 266, tv_nsec: 94209642 }, next_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, 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-30T09:10:57.391982889Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Handshake version=1 dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 len=46 pn=3 
[2025-04-30T09:10:57.391986375Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=64 blocks=[1..1] ecn_counts=None
[2025-04-30T09:10:57.391989712Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm CRYPTO off=0 len=36
[2025-04-30T09:10:57.392000622Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=99.490265ms latest_rtt=33.186703ms srtt=33.186703ms min_rtt=33.186703ms rttvar=16.593351ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=112 app_limited=true congestion_recovery_start_time=None Rate { delivered: 375, delivered_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11299, is_app_limited: true, interval: 33.186703ms, delivered: 375, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 60586937 }), send_elapsed: 0ns, ack_elapsed: 33.186703ms, rtt: 33.186703ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 508486, last_update: Instant { tv_sec: 266, tv_nsec: 94398207 }, next_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, max_datagram_size: 1350, last_packet_size: None, iv: 220.262µ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-30T09:10:57.392013667Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 dropped epoch 0 state
[2025-04-30T09:10:57.392024377Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13388 out_len=1127 left=1089
[2025-04-30T09:10:57.392037792Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=1089 pn=4 
[2025-04-30T09:10:57.392041008Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[09, 0b, d0, fa, b8, 75, 3b, 9a, 1a, ff, bc, 4f, 6d, 35, 65, 70, 97, b9, 7c, 33] reset_token=[8d, 01, a3, 9b, 88, d7, c1, 5a, 86, b9, be, 49, d6, c3, 70, aa]
[2025-04-30T09:10:57.392049044Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[04, 67, 47, 5f, 82, 94, 05, 5a, 3f, e2, 91, 42, f7, 19, f3, 5d, 8c, a8, 60, f5] reset_token=[98, 96, de, 8f, 99, de, ba, c9, c3, 37, a1, 85, 22, 01, 1d, 31]
[2025-04-30T09:10:57.392055616Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[aa, bd, 5b, ac, ee, 0f, 57, 8c, 94, e1, e8, 29, 1a, 96, f1, e1, f1, aa, 4b, 83] reset_token=[59, 68, 9c, 80, cb, d2, 8c, 3f, d8, c9, fc, 74, 17, b3, 4b, c5]
[2025-04-30T09:10:57.392061627Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[4c, 4b, dc, 09, 3b, 66, 2a, d7, 3c, 20, 0c, d6, 82, da, e1, fb, 61, e3, 1a, cf] reset_token=[1d, 79, 6d, 0e, 2b, b0, cb, d1, 78, d2, 47, a3, 8e, 64, 44, 23]
[2025-04-30T09:10:57.392067879Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[35, b8, d1, 45, 56, 42, b6, ab, d0, 10, b0, 53, e7, 66, 37, 5d, f1, 30, 31, 41] reset_token=[c9, b7, e1, d6, e1, aa, 95, 28, e7, 7f, 3a, f1, 14, ae, 8f, b9]
[2025-04-30T09:10:57.392073830Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[04, ac, 2d, af, 53, 04, 24, 30, 42, 6b, 57, 9b, f9, 30, 60, 2d, 68, 65, a4, 33] reset_token=[16, df, 98, 73, 83, 60, 38, 36, f8, 69, f0, 67, 2b, 42, 21, 53]
[2025-04-30T09:10:57.392079932Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[66, d6, 77, fc, bd, 1a, b3, 79, 87, 3b, f6, 67, f4, 07, cf, c1, c5, 2d, 38, 82] reset_token=[aa, 22, f9, 8d, b3, b1, aa, b3, c8, 6b, 47, bc, e8, b4, 6b, 9c]
[2025-04-30T09:10:57.392085913Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T09:10:57.392089069Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm PADDING len=785
[2025-04-30T09:10:57.392117873Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=99.372924ms latest_rtt=33.186703ms srtt=33.186703ms min_rtt=33.186703ms rttvar=16.593351ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1239 app_limited=true congestion_recovery_start_time=None Rate { delivered: 375, delivered_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11299, is_app_limited: true, interval: 33.186703ms, delivered: 375, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 60586937 }), send_elapsed: 0ns, ack_elapsed: 33.186703ms, rtt: 33.186703ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1127, rate: 508486, last_update: Instant { tv_sec: 266, tv_nsec: 94398207 }, next_time: Instant { tv_sec: 266, tv_nsec: 94618469 }, max_datagram_size: 1350, last_packet_size: Some(1127), 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-30T09:10:57.392173739Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.392182164Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12261 out_len=1350 left=1312
[2025-04-30T09:10:57.392186593Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=6 pn=5 
[2025-04-30T09:10:57.392189719Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T09:10:57.392196692Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=99.294155ms latest_rtt=33.186703ms srtt=33.186703ms min_rtt=33.186703ms rttvar=16.593351ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1283 app_limited=true congestion_recovery_start_time=None Rate { delivered: 375, delivered_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11299, is_app_limited: true, interval: 33.186703ms, delivered: 375, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 60586937 }), send_elapsed: 0ns, ack_elapsed: 33.186703ms, rtt: 33.186703ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 508486, last_update: Instant { tv_sec: 266, tv_nsec: 94645894 }, next_time: Instant { tv_sec: 266, tv_nsec: 94618469 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.302915ms, 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-30T09:10:57.392230616Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.392236988Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12217 out_len=1350 left=1312
[2025-04-30T09:10:57.392240895Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=6 pn=6 
[2025-04-30T09:10:57.392243941Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T09:10:57.392249992Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=99.240925ms latest_rtt=33.186703ms srtt=33.186703ms min_rtt=33.186703ms rttvar=16.593351ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1327 app_limited=true congestion_recovery_start_time=None Rate { delivered: 375, delivered_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11299, is_app_limited: true, interval: 33.186703ms, delivered: 375, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 60586937 }), send_elapsed: 0ns, ack_elapsed: 33.186703ms, rtt: 33.186703ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 44, rate: 508486, last_update: Instant { tv_sec: 266, tv_nsec: 94645894 }, next_time: Instant { tv_sec: 266, tv_nsec: 97004574 }, max_datagram_size: 1350, last_packet_size: Some(44), 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-30T09:10:57.392280269Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.392286040Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12173 out_len=1350 left=1312
[2025-04-30T09:10:57.392294937Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=73 pn=7 
[2025-04-30T09:10:57.392298103Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T09:10:57.392305276Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=99.185601ms latest_rtt=33.186703ms srtt=33.186703ms min_rtt=33.186703ms rttvar=16.593351ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1438 app_limited=true congestion_recovery_start_time=None Rate { delivered: 375, delivered_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11299, is_app_limited: true, interval: 33.186703ms, delivered: 375, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 60586937 }), send_elapsed: 0ns, ack_elapsed: 33.186703ms, rtt: 33.186703ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 508486, last_update: Instant { tv_sec: 266, tv_nsec: 94750932 }, next_time: Instant { tv_sec: 266, tv_nsec: 97004574 }, max_datagram_size: 1350, last_packet_size: None, iv: 304.826µ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-30T09:10:57.392336776Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.392343118Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12062 out_len=1350 left=1312
[2025-04-30T09:10:57.392346995Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=31 pn=8 
[2025-04-30T09:10:57.392349991Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T09:10:57.392356834Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=99.134073ms latest_rtt=33.186703ms srtt=33.186703ms min_rtt=33.186703ms rttvar=16.593351ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1507 app_limited=true congestion_recovery_start_time=None Rate { delivered: 375, delivered_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 94209672 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11299, is_app_limited: true, interval: 33.186703ms, delivered: 375, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 60586937 }), send_elapsed: 0ns, ack_elapsed: 33.186703ms, rtt: 33.186703ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 69, rate: 508486, last_update: Instant { tv_sec: 266, tv_nsec: 94750932 }, next_time: Instant { tv_sec: 266, tv_nsec: 97309400 }, max_datagram_size: 1350, last_packet_size: Some(69), 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-30T09:10:57.392386069Z TRACE quiche_apps::client] written 69 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.392390367Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.425100180Z TRACE quiche_apps::client] got 748 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.425119697Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=727 pn=2 
[2025-04-30T09:10:57.425139214Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm ACK delay=4 blocks=[4..8] ecn_counts=None
[2025-04-30T09:10:57.425144974Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 packet newly acked 4
[2025-04-30T09:10:57.425149293Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 packet newly acked 5
[2025-04-30T09:10:57.425152579Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 packet newly acked 6
[2025-04-30T09:10:57.425155254Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 packet newly acked 7
[2025-04-30T09:10:57.425158390Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 packet newly acked 8
[2025-04-30T09:10:57.425166064Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[76, 47, 94, 3d, ba, d6, 26, eb, 63, af, 66, ee, cf, 96, eb, b5, a1, 29, 8d, 63] reset_token=[c3, e6, 0b, 44, 57, 59, 05, 51, 31, 10, 5c, 7d, 1e, 80, 1e, 80]
[2025-04-30T09:10:57.425176093Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[c9, c0, 41, d5, a6, d1, 9d, 15, df, 2a, dd, d8, 94, 64, fe, 34, 03, 74, 3d, d3] reset_token=[91, bd, d3, ab, e3, a7, 73, c1, 52, f3, 93, 74, 11, 3e, 1e, 35]
[2025-04-30T09:10:57.425183397Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[8a, 71, 40, e8, 22, b9, 14, 16, f6, 82, 09, 44, dd, b9, e6, 2e, 37, fb, 77, 1f] reset_token=[fc, 93, ed, 53, 9f, ac, c9, 44, 84, f8, 91, 29, c4, cc, 02, 64]
[2025-04-30T09:10:57.425190601Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[57, 5a, a6, c3, 29, d8, e4, e2, 93, 2d, 23, 9f, 22, ec, bf, 42, 44, ee, 4b, 43] reset_token=[d5, ec, 4f, 2a, 27, 32, 43, f9, b0, 85, f3, 2f, 0a, 76, a6, e7]
[2025-04-30T09:10:57.425198125Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[04, d8, 03, dc, 05, 20, e3, 3c, eb, 40, b6, e9, 4f, 1b, 77, a7, 49, 7a, 82, fa] reset_token=[fa, 7a, 77, 82, 9d, 83, a3, 24, 17, a7, 7f, e1, ec, 6e, c9, a9]
[2025-04-30T09:10:57.425205408Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[9c, c1, de, b3, 07, fc, 16, 4c, fc, 4c, 3c, 2a, 07, ce, 35, 61, dc, 10, 04, d1] reset_token=[c6, 41, 61, c4, d6, 7f, 4a, 28, de, 3e, e2, 24, de, 5e, 06, 2e]
[2025-04-30T09:10:57.425212462Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[01, fd, e2, a6, fc, f5, 35, d1, ea, 6a, b0, 45, 8f, f0, 30, 20, 4c, 0a, 96, 54] reset_token=[24, 4c, 2f, 83, 22, b5, 66, 84, 3b, 90, 6d, 78, b1, ff, c8, 1a]
[2025-04-30T09:10:57.425218022Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm HANDSHAKE_DONE
[2025-04-30T09:10:57.425223683Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 dropped epoch 1 state
[2025-04-30T09:10:57.425227540Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm CRYPTO off=0 len=396
[2025-04-30T09:10:57.425255734Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=3 off=0 len=19 fin=false
[2025-04-30T09:10:57.425282454Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 748 bytes
[2025-04-30T09:10:57.425289477Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.425293775Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=23 pn=3 
[2025-04-30T09:10:57.425299486Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T09:10:57.425305567Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 44 bytes
[2025-04-30T09:10:57.425315747Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.425319353Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=23 pn=4 
[2025-04-30T09:10:57.425323852Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T09:10:57.425329282Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 44 bytes
[2025-04-30T09:10:57.425333670Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.425336816Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.425340253Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 3 is readable
[2025-04-30T09:10:57.425346234Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 3
[2025-04-30T09:10:57.425351444Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 open peer's control stream 3
[2025-04-30T09:10:57.425355612Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 3
[2025-04-30T09:10:57.425359399Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 3
[2025-04-30T09:10:57.425363466Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 16 bytes on stream 3
[2025-04-30T09:10:57.425366863Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(3123823206196277734, 1838055190363071988)]), additional_settings=Some([(3123823206196277734, 1838055190363071988)]) stream=3 payload_len=16
[2025-04-30T09:10:57.425376060Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 7 is readable
[2025-04-30T09:10:57.425380138Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 7
[2025-04-30T09:10:57.425384476Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 11 is readable
[2025-04-30T09:10:57.425389015Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 11
[2025-04-30T09:10:57.425400306Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:10:57.425405325Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=5 pn=9 
[2025-04-30T09:10:57.425408522Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=9 blocks=[2..4] ecn_counts=None
[2025-04-30T09:10:57.425416096Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=none latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 127862162 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, 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-30T09:10:57.425468334Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.425478974Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.426298109Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.426307687Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1329 pn=5 
[2025-04-30T09:10:57.426316483Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=0 off=0 len=1307 fin=false
[2025-04-30T09:10:57.426324789Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1350 bytes
[2025-04-30T09:10:57.426328586Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.426330991Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.426333806Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.426338044Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426341481Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 7 bytes on stream 0
[2025-04-30T09:10:57.426344596Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426347272Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm UNKNOWN raw_type=4059991675404761044 stream=0 payload_len=0
[2025-04-30T09:10:57.426367670Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426371157Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 7 bytes on stream 0
[2025-04-30T09:10:57.426374082Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426377288Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 18 bytes on stream 0
[2025-04-30T09:10:57.426379713Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm UNKNOWN raw_type=4379236500140267600 stream=0 payload_len=18
[2025-04-30T09:10:57.426383710Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426386686Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426389611Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 17 bytes on stream 0
[2025-04-30T09:10:57.426391966Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm HEADERS stream=0 payload_len=17
[2025-04-30T09:10:57.426394941Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T09:10:57.426397456Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-04-30T09:10:57.426401293Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[113, 117, 105, 99, 104, 101]
[2025-04-30T09:10:57.426405411Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[49, 48, 50, 52, 48]
[2025-04-30T09:10:57.426411613Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "quiche"), ("content-length", "10240")] on stream id 0
[2025-04-30T09:10:57.426416271Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.426419227Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426422143Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426425058Z TRACE quiche::h3::stream] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 read 1 bytes on stream 0
[2025-04-30T09:10:57.426427312Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm DATA stream=0 wire_payload_len=10240
[2025-04-30T09:10:57.426431991Z DEBUG quiche_apps::common] got 1249 bytes of response data on stream 0
[2025-04-30T09:10:57.426440127Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:10:57.426446468Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=5 pn=10 
[2025-04-30T09:10:57.426449124Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=16 blocks=[2..5] ecn_counts=None
[2025-04-30T09:10:57.426456267Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=none latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 8, last_sent_packet: 10, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 127862162 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, 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-30T09:10:57.426496903Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.426501672Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.427408312Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.427420916Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1329 pn=6 
[2025-04-30T09:10:57.427429061Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=0 off=1307 len=1306 fin=false
[2025-04-30T09:10:57.427437447Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1350 bytes
[2025-04-30T09:10:57.427441304Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.427443769Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.427446574Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.427451654Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T09:10:57.427459939Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:10:57.427463536Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=5 pn=11 
[2025-04-30T09:10:57.427466131Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=4 blocks=[2..6] ecn_counts=None
[2025-04-30T09:10:57.427473114Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=none latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 8, last_sent_packet: 11, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 127862162 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, 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-30T09:10:57.427519061Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.427523890Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.428503297Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.428516191Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1329 pn=7 
[2025-04-30T09:10:57.428524437Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=0 off=2613 len=1306 fin=false
[2025-04-30T09:10:57.428532642Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1350 bytes
[2025-04-30T09:10:57.428564212Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.428566897Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.428569802Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.428575272Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T09:10:57.428584169Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:10:57.428587736Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=5 pn=12 
[2025-04-30T09:10:57.428590321Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=8 blocks=[2..7] ecn_counts=None
[2025-04-30T09:10:57.428597725Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=none latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 8, last_sent_packet: 12, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 127862162 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, 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-30T09:10:57.428637369Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.428641758Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.429605565Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.429617998Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1329 pn=8 
[2025-04-30T09:10:57.429638176Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=0 off=3919 len=1306 fin=false
[2025-04-30T09:10:57.429646692Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1350 bytes
[2025-04-30T09:10:57.429650630Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.429653125Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.429655970Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.429661020Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T09:10:57.429669395Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:10:57.429672972Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=5 pn=13 
[2025-04-30T09:10:57.429675657Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=6 blocks=[2..8] ecn_counts=None
[2025-04-30T09:10:57.429682810Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=none latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 8, last_sent_packet: 13, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 127862162 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, 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-30T09:10:57.429730019Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.429734759Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.430709857Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.430722290Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1329 pn=9 
[2025-04-30T09:10:57.430730466Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=0 off=5225 len=1306 fin=false
[2025-04-30T09:10:57.430738761Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1350 bytes
[2025-04-30T09:10:57.430742719Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.430745184Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.430747949Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.430753108Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T09:10:57.430761384Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:10:57.430764971Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=5 pn=14 
[2025-04-30T09:10:57.430767636Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=4 blocks=[2..9] ecn_counts=None
[2025-04-30T09:10:57.430774539Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=none latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 8, last_sent_packet: 14, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 127862162 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, 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-30T09:10:57.430820606Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.430825255Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.431812917Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.431825701Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1329 pn=10 
[2025-04-30T09:10:57.431833896Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=0 off=6531 len=1306 fin=false
[2025-04-30T09:10:57.431842102Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1350 bytes
[2025-04-30T09:10:57.431846009Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.431848444Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.431851219Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.431856359Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T09:10:57.431864735Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:10:57.431868311Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=5 pn=15 
[2025-04-30T09:10:57.431870926Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=4 blocks=[2..10] ecn_counts=None
[2025-04-30T09:10:57.431877879Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=none latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 8, last_sent_packet: 15, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 127862162 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, 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-30T09:10:57.431924627Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.431929577Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.432916808Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.432929111Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1329 pn=11 
[2025-04-30T09:10:57.432937217Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=0 off=7837 len=1306 fin=false
[2025-04-30T09:10:57.432945392Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1350 bytes
[2025-04-30T09:10:57.432949289Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.432951744Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.432954539Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.432959679Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0
[2025-04-30T09:10:57.432985998Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307
[2025-04-30T09:10:57.432990016Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=5 pn=16 
[2025-04-30T09:10:57.432992631Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm ACK delay=7 blocks=[2..11] ecn_counts=None
[2025-04-30T09:10:57.432999935Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=none latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 8, last_sent_packet: 16, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 127862162 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, 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-30T09:10:57.433041113Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.433045791Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.433900653Z TRACE quiche_apps::client] got 1199 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.433913097Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1178 pn=12 
[2025-04-30T09:10:57.433921102Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=0 off=9143 len=1155 fin=true
[2025-04-30T09:10:57.433929367Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 1199 bytes
[2025-04-30T09:10:57.433934377Z TRACE quiche_apps::client] got 69 bytes from 193.167.100.100:443 to 0.0.0.0:59876
[2025-04-30T09:10:57.433937563Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=48 pn=13 
[2025-04-30T09:10:57.433947041Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 rx frm STREAM id=15 off=0 len=26 fin=true
[2025-04-30T09:10:57.433953883Z TRACE quiche_apps::client] 0.0.0.0:59876: processed 69 bytes
[2025-04-30T09:10:57.433957400Z TRACE quiche_apps::client] 0.0.0.0:59876: recv() would block
[2025-04-30T09:10:57.433959785Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.433962450Z TRACE quiche::h3] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 stream id 0 is readable
[2025-04-30T09:10:57.433968882Z DEBUG quiche_apps::common] got 1155 bytes of response data on stream 0
[2025-04-30T09:10:57.433971928Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T09:10:57.433974512Z INFO  quiche_apps::common] 1/1 response(s) received in 112.514254ms, closing...
[2025-04-30T09:10:57.433982447Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1312
[2025-04-30T09:10:57.433986284Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=11 pn=17 
[2025-04-30T09:10:57.433988829Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T09:10:57.433997536Z TRACE quiche::recovery::congestion::recovery] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 timer=110.495934ms latest_rtt=30.274478ms srtt=32.822674ms min_rtt=30.274478ms rttvar=13.173069ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1770, delivered_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 8, rate_sample: RateSample { delivery_rate: 41798, is_app_limited: true, interval: 33.374206ms, delivered: 1395, prior_delivered: 375, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 94209672 }), send_elapsed: 408.797µs, ack_elapsed: 33.374206ms, rtt: 32.965409ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 514126, last_update: Instant { tv_sec: 266, tv_nsec: 136445987 }, next_time: Instant { tv_sec: 266, tv_nsec: 127862162 }, max_datagram_size: 1350, last_packet_size: None, iv: 95.307µ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-30T09:10:57.434038754Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:59876 to 193.167.100.100:443
[2025-04-30T09:10:57.434042801Z TRACE quiche_apps::client] 0.0.0.0:59876 -> 193.167.100.100:443: done writing
[2025-04-30T09:10:57.691352846Z TRACE quiche_apps::client] timed out
[2025-04-30T09:10:57.691382071Z TRACE quiche] 7e8bf38231e73c92e203c62c91b59bdfbf3926e5 draining timeout expired
[2025-04-30T09:10:57.691424221Z TRACE quiche_apps::client] done reading
[2025-04-30T09:10:57.691428799Z INFO  quiche_apps::client] connection closed, recv=14 sent=18 lost=0 retrans=0 sent_bytes=2719 recv_bytes=12510 lost_bytes=0 [local_addr=0.0.0.0:59876 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=14 sent=18 lost=0 retrans=0 rtt=32.822674ms min_rtt=Some(30.274478ms) rttvar=13.173069ms cwnd=13500 sent_bytes=2719 recv_bytes=12510 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=41798]