[2025-04-30T10:55:59.442121144Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T10:55:59.442413862Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:54933 with scid 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce
[2025-04-30T10:55:59.442453516Z TRACE quiche::tls] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce write message lvl=Initial len=266
[2025-04-30T10:55:59.442469846Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Initial version=1 dcid=5963fd63efc60b20529faea634367332 scid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce len=270 pn=0 
[2025-04-30T10:55:59.442473022Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm CRYPTO off=0 len=266
[2025-04-30T10:55:59.442486738Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=998.933827ms 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: 288, tv_nsec: 343436707 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 343436707 }, 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: 288, tv_nsec: 343436707 }, next_time: Instant { tv_sec: 288, tv_nsec: 343436707 }, 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-30T10:55:59.442548814Z TRACE quiche_apps::client] written 1200
[2025-04-30T10:55:59.479116027Z TRACE quiche_apps::client] got 110 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.479137717Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Retry version=1 dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce scid=a0d60f622410457c token=69bf8d53fdcbd9b2ff12efe82f47ed60ce38c7141c9f7b647508e010397be5a60ac2f3aca49619c4bd5992a5ecd8235a7eac8a3bec8583393c70ec
[2025-04-30T10:55:59.479164628Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce dropped epoch 0 state
[2025-04-30T10:55:59.479173625Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 110 bytes
[2025-04-30T10:55:59.479178874Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.479182181Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.479224349Z TRACE quiche::tls] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce write message lvl=Initial len=266
[2025-04-30T10:55:59.479238426Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Initial version=1 dcid=a0d60f622410457c scid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce token=69bf8d53fdcbd9b2ff12efe82f47ed60ce38c7141c9f7b647508e010397be5a60ac2f3aca49619c4bd5992a5ecd8235a7eac8a3bec8583393c70ec len=270 pn=1 
[2025-04-30T10:55:59.479242153Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm CRYPTO off=0 len=266
[2025-04-30T10:55:59.479253033Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=998.936752ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=384 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 288, tv_nsec: 380205847 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 380205847 }, 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: 288, tv_nsec: 380205847 }, next_time: Instant { tv_sec: 288, tv_nsec: 380205847 }, 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-30T10:55:59.479319497Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.479330768Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.512487515Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.512503465Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Initial version=1 dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce scid=cb76ae1578b4ac20 token= len=120 pn=115712 
[2025-04-30T10:55:59.512514886Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm ACK delay=92 blocks=[1..1] ecn_counts=None
[2025-04-30T10:55:59.512521077Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce packet newly acked 1
[2025-04-30T10:55:59.512526838Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm CRYPTO off=0 len=90
[2025-04-30T10:55:59.512626986Z TRACE quiche::tls] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce set write secret lvl=Handshake
[2025-04-30T10:55:59.512637094Z TRACE quiche::tls] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce set read secret lvl=Handshake
[2025-04-30T10:55:59.512664796Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Handshake version=1 dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce scid=cb76ae1578b4ac20 len=737 pn=0 
[2025-04-30T10:55:59.512671879Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm CRYPTO off=0 len=713
[2025-04-30T10:55:59.512890227Z TRACE quiche::tls] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce write message lvl=Handshake len=36
[2025-04-30T10:55:59.512895127Z TRACE quiche::tls] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce set write secret lvl=OneRTT
[2025-04-30T10:55:59.512903021Z TRACE quiche::tls] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce set read secret lvl=OneRTT
[2025-04-30T10:55:59.512920384Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce 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(5963fd63efc60b20529faea634367332), max_idle_timeout: 180000, stateless_reset_token: Some(300013595836292236782230774065884165339), max_udp_payload_size: 1440, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 2097152, initial_max_stream_data_bidi_remote: 65635, initial_max_stream_data_uni: 65535, initial_max_streams_bidi: 512, initial_max_streams_uni: 512, ack_delay_exponent: 3, max_ack_delay: 10, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(cb76ae1578b4ac20), retry_source_connection_id: Some(a0d60f622410457c), max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T10:55:59.512944429Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Handshake version=1 dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce scid=cb76ae1578b4ac20 len=283 pn=1 
[2025-04-30T10:55:59.512950590Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm PING mtu_probe=None
[2025-04-30T10:55:59.512954958Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm PADDING len=262
[2025-04-30T10:55:59.512961951Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1252 bytes
[2025-04-30T10:55:59.512969506Z TRACE quiche_apps::client] got 439 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.512973533Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=418 pn=0 
[2025-04-30T10:55:59.512978773Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm CRYPTO off=0 len=125
[2025-04-30T10:55:59.513028796Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm NEW_TOKEN len=50
[2025-04-30T10:55:59.513034207Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[0c, 5e, ea, be, 96, da, d9, f2] reset_token=[ea, 94, 47, 5f, 6a, 78, 61, 42, 26, a9, 60, ca, d0, 57, 8d, 19]
[2025-04-30T10:55:59.513042071Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[48, 9e, e0, fd, 53, be, 4f, 07] reset_token=[06, 55, 89, 0c, b4, 7f, ee, 4c, bb, e5, ed, 2b, f4, 95, 18, 68]
[2025-04-30T10:55:59.513049405Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[5e, e3, cc, 9e, d4, 17, 09, 3c] reset_token=[b0, 16, db, 74, 24, 6f, 2e, ee, cc, e4, b8, 24, 1f, 20, 4a, e0]
[2025-04-30T10:55:59.513054785Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[b9, 6f, a9, 80, 3b, 4d, 1c, cd] reset_token=[7e, 88, 77, 35, 58, 31, 4f, 1e, b8, 75, 45, ff, 82, 85, d1, 91]
[2025-04-30T10:55:59.513061387Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[00, d0, 99, c6, 84, 58, 64, 60] reset_token=[9d, 36, 9f, 17, 31, c9, da, 9b, 80, d9, e0, f7, 05, 24, ca, 93]
[2025-04-30T10:55:59.513066968Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[04, c4, 01, 49, 2c, 40, 48, fe] reset_token=[7c, be, c4, 03, 7a, bb, 4b, 34, c3, 09, 86, 7d, 19, 75, a5, 8c]
[2025-04-30T10:55:59.513072688Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[ba, f6, 66, ef, 51, 9b, bf, d2] reset_token=[36, c9, 40, 64, 4e, 03, 58, 69, 0e, 24, d5, ee, 8b, ce, 61, f7]
[2025-04-30T10:55:59.513077397Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=3 off=0 len=9 fin=false
[2025-04-30T10:55:59.513082216Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T10:55:59.513085873Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T10:55:59.513089399Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm PADDING len=4
[2025-04-30T10:55:59.513101272Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 439 bytes
[2025-04-30T10:55:59.513105950Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.513109818Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.513126098Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm SETTINGS stream=2 len=18
[2025-04-30T10:55:59.513137489Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce open GREASE stream 14
[2025-04-30T10:55:59.513146136Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm GREASE stream=0 len=0
[2025-04-30T10:55:59.513151475Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm GREASE stream=0 len=18
[2025-04-30T10:55:59.513175380Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm HEADERS stream=0 len=29 fin=true
[2025-04-30T10:55:59.513181992Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /ikrwslsfit", "user-agent: quiche"]
[2025-04-30T10:55:59.513222689Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 3 is readable
[2025-04-30T10:55:59.513228249Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 3
[2025-04-30T10:55:59.513232026Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce open peer's control stream 3
[2025-04-30T10:55:59.513235262Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 3
[2025-04-30T10:55:59.513238398Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 3
[2025-04-30T10:55:59.513246203Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 6 bytes on stream 3
[2025-04-30T10:55:59.513249329Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm SETTINGS max_field_section=None, qpack_max_table=Some(0), qpack_blocked=Some(0) raw=Some([(1, 0), (7, 0), (8, 1)]), additional_settings=None stream=3 payload_len=6
[2025-04-30T10:55:59.513256211Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 7 is readable
[2025-04-30T10:55:59.513259528Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 7
[2025-04-30T10:55:59.513262884Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 11 is readable
[2025-04-30T10:55:59.513266611Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 11
[2025-04-30T10:55:59.513287620Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Initial version=1 dcid=cb76ae1578b4ac20 scid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce token=69bf8d53fdcbd9b2ff12efe82f47ed60ce38c7141c9f7b647508e010397be5a60ac2f3aca49619c4bd5992a5ecd8235a7eac8a3bec8583393c70ec len=9 pn=2 
[2025-04-30T10:55:59.513290475Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=98 blocks=[115712..115712] ecn_counts=None
[2025-04-30T10:55:59.513298280Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=99.133381ms latest_rtt=33.310414ms srtt=33.310414ms min_rtt=33.310414ms rttvar=16.655207ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 384, delivered_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11527, is_app_limited: true, interval: 33.310414ms, delivered: 384, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 380205847 }), send_elapsed: 0ns, ack_elapsed: 33.310414ms, rtt: 33.310414ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 506598, last_update: Instant { tv_sec: 288, tv_nsec: 413934072 }, next_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, 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-30T10:55:59.513315382Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Handshake version=1 dcid=cb76ae1578b4ac20 scid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce len=45 pn=3 
[2025-04-30T10:55:59.513318207Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=46 blocks=[0..1] ecn_counts=None
[2025-04-30T10:55:59.513320872Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm CRYPTO off=0 len=36
[2025-04-30T10:55:59.513326863Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=99.884225ms latest_rtt=33.310414ms srtt=33.310414ms min_rtt=33.310414ms rttvar=16.655207ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 384, delivered_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11527, is_app_limited: true, interval: 33.310414ms, delivered: 384, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 380205847 }), send_elapsed: 0ns, ack_elapsed: 33.310414ms, rtt: 33.310414ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 506598, last_update: Instant { tv_sec: 288, tv_nsec: 414295678 }, next_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, max_datagram_size: 1350, last_packet_size: None, iv: 195.421µ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-30T10:55:59.513340449Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce dropped epoch 0 state
[2025-04-30T10:55:59.513344877Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13401 out_len=1128 left=1097
[2025-04-30T10:55:59.513355637Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=1102 pn=4 
[2025-04-30T10:55:59.513358332Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=46 blocks=[0..0] ecn_counts=None
[2025-04-30T10:55:59.513360897Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[1b, c5, b9, e0, ea, 12, 4a, f6, 03, 77, 24, 55, 26, 68, 66, 69, 6e, 09, 95, a3] reset_token=[19, 0c, b2, 4b, 06, 73, 45, 16, 60, 95, ab, cc, 28, f8, 02, d3]
[2025-04-30T10:55:59.513366848Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[a1, 53, f0, 60, 7a, ec, ce, aa, c3, be, 96, 35, 2e, 71, 21, ea, f5, 28, 80, d2] reset_token=[34, ed, 37, 89, a0, e5, a0, 5b, fe, a8, b3, a0, ab, 04, b1, b7]
[2025-04-30T10:55:59.513371918Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[d7, 39, 93, be, 9f, 3b, 28, 05, da, 14, ed, 94, 70, cb, 74, a4, 59, 82, 3d, 06] reset_token=[89, 48, 39, f7, 1f, ef, 3e, 0c, 60, 0f, 80, 45, 7d, 4f, 09, 73]
[2025-04-30T10:55:59.513376947Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[cb, 2a, 4f, 68, f5, 80, 34, 70, c6, 7f, 07, e8, 10, 2d, ec, ee, 95, 4d, cb, 16] reset_token=[d3, 2c, 47, c7, 60, 5a, dd, 39, f3, 80, a3, cd, 08, 37, 88, 01]
[2025-04-30T10:55:59.513381896Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[a9, 8e, 53, a9, 16, b6, ed, 2e, 25, 4b, 3a, e5, 60, d5, f9, 0f, a7, 14, 08, 11] reset_token=[06, 36, 1e, da, f7, f3, bf, 6f, 1e, c3, 00, d8, 41, d7, 68, 29]
[2025-04-30T10:55:59.513386866Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[33, ad, 85, 48, 84, cb, c6, e3, 5f, 71, 99, 80, d0, 27, 22, df, a1, 39, 38, 69] reset_token=[e1, 04, df, b8, 6d, 97, 59, 52, 61, 41, 64, a0, ae, e2, ba, a6]
[2025-04-30T10:55:59.513391735Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[e4, 10, 30, 98, ba, 2b, 30, b3, c9, 40, 56, 25, 9c, 13, c6, 06, 9b, cb, 44, 8b] reset_token=[ba, d3, 03, f0, 72, 7f, b9, 29, 8e, ce, 04, 18, c3, 66, d5, ee]
[2025-04-30T10:55:59.513396624Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T10:55:59.513399158Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm PADDING len=793
[2025-04-30T10:55:59.513408035Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=99.803153ms latest_rtt=33.310414ms srtt=33.310414ms min_rtt=33.310414ms rttvar=16.655207ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1227 app_limited=true congestion_recovery_start_time=None Rate { delivered: 384, delivered_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11527, is_app_limited: true, interval: 33.310414ms, delivered: 384, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 380205847 }), send_elapsed: 0ns, ack_elapsed: 33.310414ms, rtt: 33.310414ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1128, rate: 506598, last_update: Instant { tv_sec: 288, tv_nsec: 414295678 }, next_time: Instant { tv_sec: 288, tv_nsec: 414491099 }, max_datagram_size: 1350, last_packet_size: Some(1128), 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-30T10:55:59.513452598Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.513463138Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12273 out_len=1350 left=1324
[2025-04-30T10:55:59.513469550Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=6 pn=5 
[2025-04-30T10:55:59.513473637Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T10:55:59.513482544Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=99.728333ms latest_rtt=33.310414ms srtt=33.310414ms min_rtt=33.310414ms rttvar=16.655207ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1259 app_limited=true congestion_recovery_start_time=None Rate { delivered: 384, delivered_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11527, is_app_limited: true, interval: 33.310414ms, delivered: 384, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 380205847 }), send_elapsed: 0ns, ack_elapsed: 33.310414ms, rtt: 33.310414ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 506598, last_update: Instant { tv_sec: 288, tv_nsec: 414475063 }, next_time: Instant { tv_sec: 288, tv_nsec: 414491099 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.289784ms, 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-30T10:55:59.513521878Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.513531466Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12241 out_len=1350 left=1324
[2025-04-30T10:55:59.513537156Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=6 pn=6 
[2025-04-30T10:55:59.513541154Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T10:55:59.513550201Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=99.660577ms latest_rtt=33.310414ms srtt=33.310414ms min_rtt=33.310414ms rttvar=16.655207ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1291 app_limited=true congestion_recovery_start_time=None Rate { delivered: 384, delivered_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11527, is_app_limited: true, interval: 33.310414ms, delivered: 384, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 380205847 }), send_elapsed: 0ns, ack_elapsed: 33.310414ms, rtt: 33.310414ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 506598, last_update: Instant { tv_sec: 288, tv_nsec: 414475063 }, next_time: Instant { tv_sec: 288, tv_nsec: 416833314 }, max_datagram_size: 1350, last_packet_size: Some(32), 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-30T10:55:59.513589424Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.513599002Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12209 out_len=1350 left=1324
[2025-04-30T10:55:59.513604943Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=72 pn=7 
[2025-04-30T10:55:59.513609031Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm STREAM id=0 off=0 len=67 fin=true
[2025-04-30T10:55:59.513617997Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=99.59285ms latest_rtt=33.310414ms srtt=33.310414ms min_rtt=33.310414ms rttvar=16.655207ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1389 app_limited=true congestion_recovery_start_time=None Rate { delivered: 384, delivered_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11527, is_app_limited: true, interval: 33.310414ms, delivered: 384, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 380205847 }), send_elapsed: 0ns, ack_elapsed: 33.310414ms, rtt: 33.310414ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 506598, last_update: Instant { tv_sec: 288, tv_nsec: 414611177 }, next_time: Instant { tv_sec: 288, tv_nsec: 416833314 }, max_datagram_size: 1350, last_packet_size: None, iv: 256.614µ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-30T10:55:59.513650859Z TRACE quiche_apps::client] written 98 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.513659525Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12111 out_len=1350 left=1324
[2025-04-30T10:55:59.513665135Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=31 pn=8 
[2025-04-30T10:55:59.513669283Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T10:55:59.513679342Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=99.531495ms latest_rtt=33.310414ms srtt=33.310414ms min_rtt=33.310414ms rttvar=16.655207ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1446 app_limited=true congestion_recovery_start_time=None Rate { delivered: 384, delivered_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11527, is_app_limited: true, interval: 33.310414ms, delivered: 384, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 380205847 }), send_elapsed: 0ns, ack_elapsed: 33.310414ms, rtt: 33.310414ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 57, rate: 506598, last_update: Instant { tv_sec: 288, tv_nsec: 414611177 }, next_time: Instant { tv_sec: 288, tv_nsec: 417089928 }, max_datagram_size: 1350, last_packet_size: Some(57), 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-30T10:55:59.513716482Z TRACE quiche_apps::client] written 57 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.513723575Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.513949266Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.513958814Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1329 pn=1 
[2025-04-30T10:55:59.513965937Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm PING mtu_probe=None
[2025-04-30T10:55:59.513973261Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm PADDING len=1311
[2025-04-30T10:55:59.513980405Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.513984162Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.514007606Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.514015961Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12054 out_len=1350 left=1319
[2025-04-30T10:55:59.514019618Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=5 pn=9 
[2025-04-30T10:55:59.514022113Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=7 blocks=[0..1] ecn_counts=None
[2025-04-30T10:55:59.514029276Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=99.181762ms latest_rtt=33.310414ms srtt=33.310414ms min_rtt=33.310414ms rttvar=16.655207ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1446 app_limited=true congestion_recovery_start_time=None Rate { delivered: 384, delivered_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 413934112 }, end_of_app_limited: 7, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 11527, is_app_limited: true, interval: 33.310414ms, delivered: 384, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 380205847 }), send_elapsed: 0ns, ack_elapsed: 33.310414ms, rtt: 33.310414ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 506598, last_update: Instant { tv_sec: 288, tv_nsec: 415027535 }, next_time: Instant { tv_sec: 288, tv_nsec: 417089928 }, max_datagram_size: 1350, last_packet_size: None, iv: 112.515µ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-30T10:55:59.514071375Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.514078989Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.546420334Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.546432957Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1329 pn=2 
[2025-04-30T10:55:59.546440732Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm ACK delay=2 blocks=[4..9] ecn_counts=None
[2025-04-30T10:55:59.546444509Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce packet newly acked 4
[2025-04-30T10:55:59.546448196Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce packet newly acked 5
[2025-04-30T10:55:59.546450771Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce packet newly acked 6
[2025-04-30T10:55:59.546452944Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce packet newly acked 7
[2025-04-30T10:55:59.546455469Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce packet newly acked 8
[2025-04-30T10:55:59.546462963Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce packet newly acked 9
[2025-04-30T10:55:59.546466841Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm HANDSHAKE_DONE
[2025-04-30T10:55:59.546470407Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce dropped epoch 1 state
[2025-04-30T10:55:59.546473202Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=0 len=23 fin=false
[2025-04-30T10:55:59.546476849Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=23 len=1277 fin=false
[2025-04-30T10:55:59.546493460Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.546497638Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.546500233Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.546503088Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.546507336Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 0
[2025-04-30T10:55:59.546520962Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 0
[2025-04-30T10:55:59.546524348Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 0
[2025-04-30T10:55:59.546527584Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 17 bytes on stream 0
[2025-04-30T10:55:59.546530109Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm HEADERS stream=0 payload_len=17
[2025-04-30T10:55:59.546533225Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T10:55:59.546535729Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-04-30T10:55:59.546538354Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[72, 51, 90, 101, 114, 111, 47, 49, 46, 48]
[2025-04-30T10:55:59.546541640Z TRACE quiche::h3::qpack::decoder] Indexed index=53 static=true
[2025-04-30T10:55:59.546547621Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "H3Zero/1.0"), ("content-type", "text/plain")] on stream id 0
[2025-04-30T10:55:59.546552090Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.546555106Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 0
[2025-04-30T10:55:59.546558071Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 0
[2025-04-30T10:55:59.546561207Z TRACE quiche::h3::stream] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce read 1 bytes on stream 0
[2025-04-30T10:55:59.546563551Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm DATA stream=0 wire_payload_len=10240
[2025-04-30T10:55:59.546568711Z DEBUG quiche_apps::common] got 1277 bytes of response data on stream 0
[2025-04-30T10:55:59.546576666Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:55:59.546580273Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=5 pn=10 
[2025-04-30T10:55:59.546582797Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=18 blocks=[2..2] ecn_counts=None
[2025-04-30T10:55:59.546589359Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=none latest_rtt=30.357058ms srtt=32.941244ms min_rtt=30.357058ms rttvar=13.229744ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1731, delivered_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 9, rate_sample: RateSample { delivery_rate: 40193, is_app_limited: true, interval: 33.512874ms, delivered: 1347, prior_delivered: 384, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 413934112 }), send_elapsed: 556.987µs, ack_elapsed: 33.512874ms, rtt: 32.955887ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512275, last_update: Instant { tv_sec: 288, tv_nsec: 447588620 }, next_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, 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-30T10:55:59.546635686Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.546643591Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.547516211Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.547524056Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1329 pn=3 
[2025-04-30T10:55:59.547530598Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=1300 len=1308 fin=false
[2025-04-30T10:55:59.547537691Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.547541218Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.547543582Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.547546147Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.547550646Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:55:59.547557248Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:55:59.547560354Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=5 pn=11 
[2025-04-30T10:55:59.547562738Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=4 blocks=[2..3] ecn_counts=None
[2025-04-30T10:55:59.547568830Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=none latest_rtt=30.357058ms srtt=32.941244ms min_rtt=30.357058ms rttvar=13.229744ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1731, delivered_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, end_of_app_limited: 9, last_sent_packet: 11, largest_acked: 9, rate_sample: RateSample { delivery_rate: 40193, is_app_limited: true, interval: 33.512874ms, delivered: 1347, prior_delivered: 384, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 413934112 }), send_elapsed: 556.987µs, ack_elapsed: 33.512874ms, rtt: 32.955887ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512275, last_update: Instant { tv_sec: 288, tv_nsec: 447588620 }, next_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, 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-30T10:55:59.547605128Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.547612261Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.554422767Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.554431584Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1329 pn=4 
[2025-04-30T10:55:59.554442955Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=2608 len=1308 fin=false
[2025-04-30T10:55:59.554450219Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.554453725Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.554456090Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.554458594Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.554463073Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:55:59.554469515Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:55:59.554472690Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=5 pn=12 
[2025-04-30T10:55:59.554475075Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=4 blocks=[2..4] ecn_counts=None
[2025-04-30T10:55:59.554481086Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=none latest_rtt=30.357058ms srtt=32.941244ms min_rtt=30.357058ms rttvar=13.229744ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1731, delivered_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, end_of_app_limited: 9, last_sent_packet: 12, largest_acked: 9, rate_sample: RateSample { delivery_rate: 40193, is_app_limited: true, interval: 33.512874ms, delivered: 1347, prior_delivered: 384, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 413934112 }), send_elapsed: 556.987µs, ack_elapsed: 33.512874ms, rtt: 32.955887ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512275, last_update: Instant { tv_sec: 288, tv_nsec: 447588620 }, next_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, 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-30T10:55:59.554516653Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.554523445Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.562321077Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.562330354Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1329 pn=5 
[2025-04-30T10:55:59.562337778Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=3916 len=1308 fin=false
[2025-04-30T10:55:59.562345663Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.562349951Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.562352886Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.562355952Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.562361222Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:55:59.562368776Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:55:59.562372513Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=5 pn=13 
[2025-04-30T10:55:59.562375509Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=4 blocks=[2..5] ecn_counts=None
[2025-04-30T10:55:59.562388723Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=none latest_rtt=30.357058ms srtt=32.941244ms min_rtt=30.357058ms rttvar=13.229744ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1731, delivered_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, end_of_app_limited: 9, last_sent_packet: 13, largest_acked: 9, rate_sample: RateSample { delivery_rate: 40193, is_app_limited: true, interval: 33.512874ms, delivered: 1347, prior_delivered: 384, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 413934112 }), send_elapsed: 556.987µs, ack_elapsed: 33.512874ms, rtt: 32.955887ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512275, last_update: Instant { tv_sec: 288, tv_nsec: 447588620 }, next_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, 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-30T10:55:59.562436903Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.562445850Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.570282905Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.570292362Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1329 pn=6 
[2025-04-30T10:55:59.570299636Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=5224 len=1308 fin=false
[2025-04-30T10:55:59.570307620Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.570311819Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.570314724Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.570317770Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.570322999Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:55:59.570330603Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:55:59.570334381Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=5 pn=14 
[2025-04-30T10:55:59.570337276Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=4 blocks=[2..6] ecn_counts=None
[2025-04-30T10:55:59.570344139Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=none latest_rtt=30.357058ms srtt=32.941244ms min_rtt=30.357058ms rttvar=13.229744ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1731, delivered_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, end_of_app_limited: 9, last_sent_packet: 14, largest_acked: 9, rate_sample: RateSample { delivery_rate: 40193, is_app_limited: true, interval: 33.512874ms, delivered: 1347, prior_delivered: 384, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 413934112 }), send_elapsed: 556.987µs, ack_elapsed: 33.512874ms, rtt: 32.955887ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512275, last_update: Instant { tv_sec: 288, tv_nsec: 447588620 }, next_time: Instant { tv_sec: 288, tv_nsec: 447701135 }, 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-30T10:55:59.570391227Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.570400003Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.578109099Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.578118116Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1329 pn=7 
[2025-04-30T10:55:59.578124688Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=6532 len=1308 fin=false
[2025-04-30T10:55:59.578131832Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.578135438Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.578137783Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.578140357Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.578144776Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:55:59.578151318Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:55:59.578154434Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=5 pn=15 
[2025-04-30T10:55:59.578156838Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=4 blocks=[2..7] ecn_counts=None
[2025-04-30T10:55:59.578162589Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=none latest_rtt=30.357058ms srtt=32.941244ms min_rtt=30.357058ms rttvar=13.229744ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1731, delivered_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, end_of_app_limited: 9, last_sent_packet: 15, largest_acked: 9, rate_sample: RateSample { delivery_rate: 40193, is_app_limited: true, interval: 33.512874ms, delivered: 1347, prior_delivered: 384, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 413934112 }), send_elapsed: 556.987µs, ack_elapsed: 33.512874ms, rtt: 32.955887ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512275, last_update: Instant { tv_sec: 288, tv_nsec: 479164044 }, next_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, 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-30T10:55:59.578197745Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.578205058Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.584447513Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.584458875Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1329 pn=8 
[2025-04-30T10:55:59.584466910Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=7840 len=1308 fin=false
[2025-04-30T10:55:59.584475526Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.584479914Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.584482860Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.584486025Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.584497757Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:55:59.584522143Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:55:59.584526290Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=5 pn=16 
[2025-04-30T10:55:59.584529286Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm ACK delay=7 blocks=[2..8] ecn_counts=None
[2025-04-30T10:55:59.584536840Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=none latest_rtt=30.357058ms srtt=32.941244ms min_rtt=30.357058ms rttvar=13.229744ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1731, delivered_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, end_of_app_limited: 9, last_sent_packet: 16, largest_acked: 9, rate_sample: RateSample { delivery_rate: 40193, is_app_limited: true, interval: 33.512874ms, delivered: 1347, prior_delivered: 384, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 413934112 }), send_elapsed: 556.987µs, ack_elapsed: 33.512874ms, rtt: 32.955887ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512275, last_update: Instant { tv_sec: 288, tv_nsec: 479164044 }, next_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, 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-30T10:55:59.584581724Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.584590801Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.589521535Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.589530882Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx pkt Short dcid=4b7d51178ff5fde6db160e7c1862f98b8c74f4ce key_phase=false len=1186 pn=9 
[2025-04-30T10:55:59.589538326Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm STREAM id=0 off=9148 len=1115 fin=true
[2025-04-30T10:55:59.589542715Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce rx frm PADDING len=48
[2025-04-30T10:55:59.589550449Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1207 bytes
[2025-04-30T10:55:59.589554757Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.589557763Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.589560858Z TRACE quiche::h3] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce stream id 0 is readable
[2025-04-30T10:55:59.589567521Z DEBUG quiche_apps::common] got 1115 bytes of response data on stream 0
[2025-04-30T10:55:59.589570827Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T10:55:59.589573923Z INFO  quiche_apps::common] 1/1 response(s) received in 147.019399ms, closing...
[2025-04-30T10:55:59.589581507Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T10:55:59.589585564Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx pkt Short dcid=cb76ae1578b4ac20 key_phase=false len=11 pn=17 
[2025-04-30T10:55:59.589588620Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T10:55:59.589596625Z TRACE quiche::recovery::congestion::recovery] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce timer=95.842177ms latest_rtt=30.357058ms srtt=32.941244ms min_rtt=30.357058ms rttvar=13.229744ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1731, delivered_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, first_sent_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 9, rate_sample: RateSample { delivery_rate: 40193, is_app_limited: true, interval: 33.512874ms, delivered: 1347, prior_delivered: 384, prior_time: Some(Instant { tv_sec: 288, tv_nsec: 413934112 }), send_elapsed: 556.987µs, ack_elapsed: 33.512874ms, rtt: 32.955887ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 512275, last_update: Instant { tv_sec: 288, tv_nsec: 490594434 }, next_time: Instant { tv_sec: 288, tv_nsec: 479164044 }, max_datagram_size: 1350, last_packet_size: None, iv: 72.227µ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-30T10:55:59.589646178Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:54933 to 193.167.100.100:443
[2025-04-30T10:55:59.589654904Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.593903804Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.593912450Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.593916578Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.593919483Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.593923851Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.598273940Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.598281815Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.598285752Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.598288558Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.598292525Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.602402465Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.602408526Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1207 bytes
[2025-04-30T10:55:59.602412293Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.602415089Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.602418825Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.606333270Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.606339352Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.606342477Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.606344732Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.606347888Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.609687190Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.609694493Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1350 bytes
[2025-04-30T10:55:59.609697739Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.609700043Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.609703450Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.612474127Z TRACE quiche_apps::client] got 1207 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.612480269Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 1207 bytes
[2025-04-30T10:55:59.612484076Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.612492782Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.612496780Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.620057678Z TRACE quiche_apps::client] got 43 bytes from 193.167.100.100:443 to 0.0.0.0:54933
[2025-04-30T10:55:59.620063990Z TRACE quiche_apps::client] 0.0.0.0:54933: processed 43 bytes
[2025-04-30T10:55:59.620067857Z TRACE quiche_apps::client] 0.0.0.0:54933: recv() would block
[2025-04-30T10:55:59.620070673Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.620074380Z TRACE quiche_apps::client] 0.0.0.0:54933 -> 193.167.100.100:443: done writing
[2025-04-30T10:55:59.848354344Z TRACE quiche_apps::client] timed out
[2025-04-30T10:55:59.848374732Z TRACE quiche] 4b7d51178ff5fde6db160e7c1862f98b8c74f4ce draining timeout expired
[2025-04-30T10:55:59.848414136Z TRACE quiche_apps::client] done reading
[2025-04-30T10:55:59.848418775Z INFO  quiche_apps::client] connection closed, recv=13 sent=18 lost=0 retrans=0 sent_bytes=2571 recv_bytes=13698 lost_bytes=0 [local_addr=0.0.0.0:54933 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=13 sent=18 lost=0 retrans=0 rtt=32.941244ms min_rtt=Some(30.357058ms) rttvar=13.229744ms cwnd=13500 sent_bytes=2571 recv_bytes=13698 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=40193]