[2025-09-11T16:44:54.175080056Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-09-11T16:44:54.175440632Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:40666 with scid 78dc2f12eeb374f1640d9a71410f3e6deb36ae16
[2025-09-11T16:44:54.175487440Z TRACE quiche::tls] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 write message lvl=Initial len=266
[2025-09-11T16:44:54.175505474Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Initial version=1 dcid=20681ffca617aff1ec6f3f37801f1066 scid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 len=270 pn=0 
[2025-09-11T16:44:54.175508770Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm CRYPTO off=0 len=266
[2025-09-11T16:44:54.175523598Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=998.924148ms 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: 485, tv_nsec: 879419416 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 485, tv_nsec: 879419416 }, next_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, 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-09-11T16:44:54.175577619Z TRACE quiche_apps::client] written 1200
[2025-09-11T16:44:55.175632551Z TRACE quiche_apps::client] timed out
[2025-09-11T16:44:55.175656165Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 loss detection timeout expired
[2025-09-11T16:44:55.175663329Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=997.783946ms 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: 485, tv_nsec: 879419416 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 485, tv_nsec: 879419416 }, next_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, 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-09-11T16:44:55.175692984Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:55.175732258Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Initial version=1 dcid=20681ffca617aff1ec6f3f37801f1066 scid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 len=270 pn=1 
[2025-09-11T16:44:55.175736836Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm CRYPTO off=0 len=266
[2025-09-11T16:44:55.175756894Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=1.997949264s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=666 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 486, tv_nsec: 879677588 }, next_time: Instant { tv_sec: 486, tv_nsec: 879677588 }, 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-09-11T16:44:55.175827507Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:55.175835281Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:55.684114560Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:55.684153072Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Initial version=1 dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 scid=40000054702ea919 token= len=1194 pn=2897089 
[2025-09-11T16:44:55.684176186Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm CRYPTO off=0 len=90
[2025-09-11T16:44:55.684339402Z TRACE quiche::tls] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 set write secret lvl=Handshake
[2025-09-11T16:44:55.684356494Z TRACE quiche::tls] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 set read secret lvl=Handshake
[2025-09-11T16:44:55.684371993Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm ACK delay=127 blocks=[0..0] ecn_counts=None
[2025-09-11T16:44:55.684381551Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 packet newly acked 0
[2025-09-11T16:44:55.684398503Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm PADDING len=1075
[2025-09-11T16:44:55.684431575Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 1232 bytes
[2025-09-11T16:44:55.684437516Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:55.684441193Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:55.684457964Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Initial version=1 dcid=40000054702ea919 scid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 len=8 pn=2 
[2025-09-11T16:44:55.684462202Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=38 blocks=[2897089..2897089] ecn_counts=None
[2025-09-11T16:44:55.684473704Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.01732428s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 486, tv_nsec: 879677588 }, next_time: Instant { tv_sec: 486, tv_nsec: 879677588 }, 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-09-11T16:44:55.684534548Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:55.684541491Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:55.684637000Z TRACE quiche_apps::client] got 773 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:55.684649844Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Handshake version=1 dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 scid=40000054702ea919 len=736 pn=2897089 
[2025-09-11T16:44:55.684666415Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm CRYPTO off=0 len=713
[2025-09-11T16:44:55.684947602Z TRACE quiche::tls] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 write message lvl=Handshake len=36
[2025-09-11T16:44:55.684953533Z TRACE quiche::tls] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 set write secret lvl=OneRTT
[2025-09-11T16:44:55.684964184Z TRACE quiche::tls] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 set read secret lvl=OneRTT
[2025-09-11T16:44:55.684986806Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 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(20681ffca617aff1ec6f3f37801f1066), max_idle_timeout: 60000, stateless_reset_token: Some(56708299859743437895245905982503991161), max_udp_payload_size: 1500, initial_max_data: 107374182, initial_max_stream_data_bidi_local: 107374182, initial_max_stream_data_bidi_remote: 107374182, initial_max_stream_data_uni: 107374182, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 2, initial_source_connection_id: Some(40000054702ea919), retry_source_connection_id: None, max_datagram_frame_size: Some(65535), unknown_params: None }
[2025-09-11T16:44:55.685021511Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 773 bytes
[2025-09-11T16:44:55.685028354Z TRACE quiche_apps::client] got 112 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:55.685060694Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Short dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 key_phase=false len=91 pn=2897089 
[2025-09-11T16:44:55.685069241Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=3 off=0 len=41 fin=false
[2025-09-11T16:44:55.685075292Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-09-11T16:44:55.685079650Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-09-11T16:44:55.685084329Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm PADDING len=20
[2025-09-11T16:44:55.685092584Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 112 bytes
[2025-09-11T16:44:55.685097383Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:55.685100870Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:55.685124354Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm SETTINGS stream=2 len=18
[2025-09-11T16:44:55.685142298Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 open GREASE stream 14
[2025-09-11T16:44:55.685155753Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm GREASE stream=0 len=0
[2025-09-11T16:44:55.685165010Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm GREASE stream=0 len=18
[2025-09-11T16:44:55.685175720Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm HEADERS stream=0 len=30 fin=true
[2025-09-11T16:44:55.685184667Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /fpoygglwjv", "user-agent: quiche"]
[2025-09-11T16:44:55.685242215Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 stream id 3 is readable
[2025-09-11T16:44:55.685255630Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 3
[2025-09-11T16:44:55.685261250Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 open peer's control stream 3
[2025-09-11T16:44:55.685266120Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 3
[2025-09-11T16:44:55.685270768Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 3
[2025-09-11T16:44:55.685293952Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 38 bytes on stream 3
[2025-09-11T16:44:55.685299472Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm SETTINGS max_field_section=Some(131072), qpack_max_table=Some(4096), qpack_blocked=Some(100) raw=Some([(1, 4096), (6, 131072), (7, 100), (8, 1), (16765559, 1), (630, 1), (51, 1), (727725890, 1), (281, 4207849484)]), additional_settings=Some([(16765559, 1), (727725890, 1), (281, 4207849484)]) stream=3 payload_len=38
[2025-09-11T16:44:55.685316193Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 stream id 7 is readable
[2025-09-11T16:44:55.685321554Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 7
[2025-09-11T16:44:55.685326663Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 stream id 11 is readable
[2025-09-11T16:44:55.685332234Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 11
[2025-09-11T16:44:55.685351119Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Handshake version=1 dcid=40000054702ea919 scid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 len=49 pn=3 
[2025-09-11T16:44:55.685355357Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=87 blocks=[2897089..2897089] ecn_counts=None
[2025-09-11T16:44:55.685359986Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm CRYPTO off=0 len=36
[2025-09-11T16:44:55.685373211Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.016424744s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=436 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 103, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 388955731 }, next_time: Instant { tv_sec: 487, tv_nsec: 388955761 }, max_datagram_size: 1350, last_packet_size: Some(103), 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-09-11T16:44:55.685399189Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 dropped epoch 0 state
[2025-09-11T16:44:55.685409188Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=72 pn=4 
[2025-09-11T16:44:55.685412374Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=43 blocks=[2897089..2897089] ecn_counts=None
[2025-09-11T16:44:55.685415560Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[5b, 59, 8d, e8, 23, 4c, f2, 79, 40, 60, 67, 70, f5, bb, 87, 18, 78, e6, f0, 4b] reset_token=[62, ff, 44, e6, 4f, f1, 0a, 70, d1, 3c, 5f, df, a5, 98, 97, bf]
[2025-09-11T16:44:55.685425168Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-09-11T16:44:55.685437451Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.525993021s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=201 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 389309765 }, next_time: Instant { tv_sec: 487, tv_nsec: 388955761 }, max_datagram_size: 1350, last_packet_size: None, iv: 17.970496ms, 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-09-11T16:44:55.685476224Z TRACE quiche_apps::client] written 201 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:55.685484399Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=6 pn=5 
[2025-09-11T16:44:55.685487425Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-09-11T16:44:55.685493677Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.525936865s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=233 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 389309765 }, next_time: Instant { tv_sec: 487, tv_nsec: 407421926 }, 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-09-11T16:44:55.685526638Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:55.685538761Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=6 pn=6 
[2025-09-11T16:44:55.685543550Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-09-11T16:44:55.685554140Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.525875811s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=265 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 64, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 389309765 }, next_time: Instant { tv_sec: 487, tv_nsec: 407421926 }, 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-09-11T16:44:55.685613682Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:55.685624442Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=73 pn=7 
[2025-09-11T16:44:55.685629782Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-09-11T16:44:55.685646042Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.525784079s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=364 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 389589219 }, next_time: Instant { tv_sec: 487, tv_nsec: 407421926 }, max_datagram_size: 1350, last_packet_size: None, iv: 14.573089ms, 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-09-11T16:44:55.685693852Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:55.685703811Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=31 pn=8 
[2025-09-11T16:44:55.685709040Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm STREAM id=14 off=0 len=26 fin=true
[2025-09-11T16:44:55.685721514Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.525708567s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=421 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 57, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 389589219 }, next_time: Instant { tv_sec: 487, tv_nsec: 421995015 }, 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-09-11T16:44:55.685773551Z TRACE quiche_apps::client] written 57 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:55.685778901Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:55.793590582Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:55.793613826Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 dropped invalid packet
[2025-09-11T16:44:55.793620929Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 1232 bytes
[2025-09-11T16:44:55.793627341Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:55.793631860Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:55.793640245Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:55.794214352Z TRACE quiche_apps::client] got 773 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:55.794240711Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Handshake version=1 dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 scid=40000054702ea919 len=736 pn=2897091 
[2025-09-11T16:44:55.794259206Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm CRYPTO off=0 len=713
[2025-09-11T16:44:55.794280686Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 773 bytes
[2025-09-11T16:44:55.794288571Z TRACE quiche_apps::client] got 93 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:55.794294963Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Short dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 key_phase=false len=72 pn=2897091 
[2025-09-11T16:44:55.794304170Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=3 off=0 len=41 fin=false
[2025-09-11T16:44:55.794309681Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-09-11T16:44:55.794314430Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-09-11T16:44:55.794319249Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm PADDING len=1
[2025-09-11T16:44:55.794329278Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 93 bytes
[2025-09-11T16:44:55.794335139Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:55.794339547Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:55.794357721Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Handshake version=1 dcid=40000054702ea919 scid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 len=10 pn=9 
[2025-09-11T16:44:55.794363141Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=14 blocks=[2897089..2897089, 2897091..2897091] ecn_counts=None
[2025-09-11T16:44:55.794377949Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.417051801s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=421 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 7, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 498316637 }, next_time: Instant { tv_sec: 487, tv_nsec: 421995015 }, max_datagram_size: 1350, last_packet_size: None, iv: 5.096111ms, 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-09-11T16:44:55.794422512Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=10 pn=10 
[2025-09-11T16:44:55.794427712Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=15 blocks=[2897089..2897089, 2897091..2897091] ecn_counts=None
[2025-09-11T16:44:55.794437841Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.41699216s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=421 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 7, last_sent_packet: 10, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 498316637 }, next_time: Instant { tv_sec: 487, tv_nsec: 503412748 }, 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-09-11T16:44:55.794491602Z TRACE quiche_apps::client] written 100 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:55.794499256Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:56.004140387Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:56.004158972Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 dropped invalid packet
[2025-09-11T16:44:56.004164172Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 1232 bytes
[2025-09-11T16:44:56.004170043Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:56.004174061Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:56.004181936Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:56.004754109Z TRACE quiche_apps::client] got 773 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:56.004772483Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Handshake version=1 dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 scid=40000054702ea919 len=736 pn=2897093 
[2025-09-11T16:44:56.004789916Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm CRYPTO off=0 len=713
[2025-09-11T16:44:56.004808300Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 773 bytes
[2025-09-11T16:44:56.004815534Z TRACE quiche_apps::client] got 93 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:56.004821164Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Short dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 key_phase=false len=72 pn=2897093 
[2025-09-11T16:44:56.004829290Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=3 off=0 len=41 fin=false
[2025-09-11T16:44:56.004834259Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-09-11T16:44:56.004838567Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-09-11T16:44:56.004842805Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm PADDING len=1
[2025-09-11T16:44:56.004851652Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 93 bytes
[2025-09-11T16:44:56.004856941Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:56.004868172Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:56.004885605Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Handshake version=1 dcid=40000054702ea919 scid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 len=12 pn=11 
[2025-09-11T16:44:56.004890344Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=14 blocks=[2897089..2897089, 2897091..2897091, 2897093..2897093] ecn_counts=None
[2025-09-11T16:44:56.004903669Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.206526372s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=421 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 7, last_sent_packet: 11, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 498316637 }, next_time: Instant { tv_sec: 487, tv_nsec: 503412748 }, 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-09-11T16:44:56.004935298Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=12 pn=12 
[2025-09-11T16:44:56.004939686Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=14 blocks=[2897089..2897089, 2897091..2897091, 2897093..2897093] ecn_counts=None
[2025-09-11T16:44:56.004950477Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=4.206479474s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=421 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 7, last_sent_packet: 12, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 498316637 }, next_time: Instant { tv_sec: 487, tv_nsec: 503412748 }, 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-09-11T16:44:56.005008005Z TRACE quiche_apps::client] written 104 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:56.005016010Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:56.414842439Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:56.414865092Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 dropped invalid packet
[2025-09-11T16:44:56.414869911Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 1232 bytes
[2025-09-11T16:44:56.414875161Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:56.414888416Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:56.414897372Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:56.415456461Z TRACE quiche_apps::client] got 773 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:56.415477120Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Handshake version=1 dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 scid=40000054702ea919 len=736 pn=2897095 
[2025-09-11T16:44:56.415493059Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm CRYPTO off=0 len=713
[2025-09-11T16:44:56.415512055Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 773 bytes
[2025-09-11T16:44:56.415518638Z TRACE quiche_apps::client] got 93 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:56.415523266Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Short dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 key_phase=false len=72 pn=2897095 
[2025-09-11T16:44:56.415530159Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=3 off=0 len=41 fin=false
[2025-09-11T16:44:56.415534357Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-09-11T16:44:56.415538004Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-09-11T16:44:56.415541570Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm PADDING len=1
[2025-09-11T16:44:56.415548704Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 93 bytes
[2025-09-11T16:44:56.415553363Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:56.415556679Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:56.415571697Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Handshake version=1 dcid=40000054702ea919 scid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 len=14 pn=13 
[2025-09-11T16:44:56.415575644Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=11 blocks=[2897089..2897089, 2897091..2897091, 2897093..2897093, 2897095..2897095] ecn_counts=None
[2025-09-11T16:44:56.415588028Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=3.795842194s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=421 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 7, last_sent_packet: 13, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 498316637 }, next_time: Instant { tv_sec: 487, tv_nsec: 503412748 }, 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-09-11T16:44:56.415613666Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=14 pn=14 
[2025-09-11T16:44:56.415617283Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=11 blocks=[2897089..2897089, 2897091..2897091, 2897093..2897093, 2897095..2897095] ecn_counts=None
[2025-09-11T16:44:56.415624626Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=3.795805816s latest_rtt=1.508697322s srtt=1.508697322s min_rtt=1.508697322s rttvar=754.348661ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=421 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 487, tv_nsec: 388116738 }, first_sent_time: Instant { tv_sec: 485, tv_nsec: 879419416 }, end_of_app_limited: 7, last_sent_packet: 14, largest_acked: 0, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.508697322s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 485, tv_nsec: 879419416 }), send_elapsed: 0ns, ack_elapsed: 1.508697322s, rtt: 1.508697322s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11185, last_update: Instant { tv_sec: 487, tv_nsec: 498316637 }, next_time: Instant { tv_sec: 487, tv_nsec: 503412748 }, 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-09-11T16:44:56.415684268Z TRACE quiche_apps::client] written 108 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:56.415691011Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:57.186639698Z TRACE quiche_apps::client] got 58 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:57.186668823Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Handshake version=1 dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 scid=40000054702ea919 len=22 pn=2897096 
[2025-09-11T16:44:57.186685243Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm ACK delay=54 blocks=[3..3] ecn_counts=None
[2025-09-11T16:44:57.186692837Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 packet newly acked 3
[2025-09-11T16:44:57.186747520Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 58 bytes
[2025-09-11T16:44:57.186754433Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:57.186758601Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:57.186769221Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:57.186992740Z TRACE quiche_apps::client] got 432 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:57.187006476Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Short dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 key_phase=false len=411 pn=2897096 
[2025-09-11T16:44:57.187018719Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm CRYPTO off=0 len=242
[2025-09-11T16:44:57.187078571Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm ACK delay=63 blocks=[4..4] ecn_counts=None
[2025-09-11T16:44:57.187085624Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 packet newly acked 4
[2025-09-11T16:44:57.187092106Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm HANDSHAKE_DONE
[2025-09-11T16:44:57.187098618Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 dropped epoch 1 state
[2025-09-11T16:44:57.187106453Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[40, 00, 00, 44, 66, e2, e2, a4] reset_token=[03, f2, a7, 7d, 6e, 06, 98, 41, 8e, c1, 25, 0a, 43, 34, ee, 32]
[2025-09-11T16:44:57.187119868Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[40, 00, 00, 49, 8d, b4, 0f, 5a] reset_token=[fd, 48, 29, 93, b3, 82, 8b, 42, 5e, 73, 66, b9, ea, f9, 42, 64]
[2025-09-11T16:44:57.187129496Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[40, 00, 00, 45, ba, 60, 8b, ec] reset_token=[e7, 8c, 39, 5a, 9e, 0f, 62, 27, 4f, 7f, 17, c7, 42, db, 10, 47]
[2025-09-11T16:44:57.187139084Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[40, 00, 00, 5d, e6, 2a, cc, cb] reset_token=[6c, db, 0f, 0f, aa, 9e, 56, 06, 08, 9d, 1a, 08, e6, 05, 9a, 0d]
[2025-09-11T16:44:57.187154703Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm PADDING len=30
[2025-09-11T16:44:57.187184479Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 432 bytes
[2025-09-11T16:44:57.187196893Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:57.187205739Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:57.187230897Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=12 pn=15 
[2025-09-11T16:44:57.187240725Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=27 blocks=[2897091..2897091, 2897093..2897093, 2897095..2897096] ecn_counts=None
[2025-09-11T16:44:57.187262325Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=1.738877628s latest_rtt=1.502019449s srtt=1.507094993s min_rtt=1.501679321s rttvar=427.087151ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=220 app_limited=true congestion_recovery_start_time=None Rate { delivered: 534, delivered_time: Instant { tv_sec: 488, tv_nsec: 890975210 }, first_sent_time: Instant { tv_sec: 487, tv_nsec: 388955761 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 4, rate_sample: RateSample { bandwidth: 1.76 Kbps, is_app_limited: true, interval: 1.509536345s, delivered: 201, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 487, tv_nsec: 388116738 }), send_elapsed: 1.509536345s, ack_elapsed: 1.502858472s, rtt: 1.502019449s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11197, last_update: Instant { tv_sec: 488, tv_nsec: 891186897 }, next_time: Instant { tv_sec: 488, tv_nsec: 891186897 }, 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-09-11T16:44:57.187327558Z TRACE quiche_apps::client] written 38 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:57.187334380Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:57.187344229Z TRACE quiche_apps::client] got 112 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:57.187349759Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Short dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 key_phase=false len=91 pn=2897097 
[2025-09-11T16:44:57.187358636Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm ACK delay=108 blocks=[4..7] ecn_counts=None
[2025-09-11T16:44:57.187363445Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 packet newly acked 5
[2025-09-11T16:44:57.187366952Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 packet newly acked 6
[2025-09-11T16:44:57.187370168Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 packet newly acked 7
[2025-09-11T16:44:57.187376319Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=0 off=0 len=62 fin=false
[2025-09-11T16:44:57.187384224Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm PADDING len=3
[2025-09-11T16:44:57.187409261Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 112 bytes
[2025-09-11T16:44:57.187416615Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:57.187420462Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:57.187424870Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 stream id 0 is readable
[2025-09-11T16:44:57.187432054Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 0
[2025-09-11T16:44:57.187439568Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 0
[2025-09-11T16:44:57.187456760Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 0
[2025-09-11T16:44:57.187463914Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm UNKNOWN raw_type=281 stream=0 payload_len=0
[2025-09-11T16:44:57.187473161Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 0
[2025-09-11T16:44:57.187477599Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 0
[2025-09-11T16:44:57.187482869Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 57 bytes on stream 0
[2025-09-11T16:44:57.187486426Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm HEADERS stream=0 payload_len=57
[2025-09-11T16:44:57.187492808Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-09-11T16:44:57.187496805Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-09-11T16:44:57.187505692Z TRACE quiche::h3::qpack::decoder] Literal name_idx=83 static=true value=[104, 51, 61, 34, 58, 52, 52, 51, 34, 59, 32, 109, 97, 61, 51, 54, 48, 48]
[2025-09-11T16:44:57.187512825Z TRACE quiche::h3::qpack::decoder] Literal Without Name Reference name=[112, 114, 105, 111, 114, 105, 116, 121] value=[117, 61, 51, 44, 105]
[2025-09-11T16:44:57.187520139Z TRACE quiche::h3::qpack::decoder] Literal name_idx=6 static=true value=[84, 104, 117, 44, 32, 49, 49, 32, 83, 101, 112, 32, 50, 48, 50, 53, 32, 49, 54, 58, 52, 52, 58, 53, 54, 32, 71, 77, 84]
[2025-09-11T16:44:57.187532993Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("alt-svc", "h3=\":443\"; ma=3600"), ("priority", "u=3,i"), ("date", "Thu, 11 Sep 2025 16:44:56 GMT")] on stream id 0
[2025-09-11T16:44:57.187548492Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=12 pn=16 
[2025-09-11T16:44:57.187552449Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm ACK delay=24 blocks=[2897091..2897091, 2897093..2897093, 2897095..2897097] ecn_counts=None
[2025-09-11T16:44:57.187563109Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=1.331788541s latest_rtt=1.483897429s srtt=1.504195296s min_rtt=1.483897429s rttvar=326.114754ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=57 app_limited=true congestion_recovery_start_time=None Rate { delivered: 697, delivered_time: Instant { tv_sec: 488, tv_nsec: 891319355 }, first_sent_time: Instant { tv_sec: 487, tv_nsec: 407421926 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 7, rate_sample: RateSample { bandwidth: 1.90 Kbps, is_app_limited: true, interval: 1.52800251s, delivered: 364, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 487, tv_nsec: 388116738 }), send_elapsed: 1.52800251s, ack_elapsed: 1.503202617s, rtt: 1.483897429s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11218, last_update: Instant { tv_sec: 488, tv_nsec: 891186897 }, next_time: Instant { tv_sec: 488, tv_nsec: 891186897 }, 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-09-11T16:44:57.187608334Z TRACE quiche_apps::client] written 38 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:57.187613995Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:57.188145081Z TRACE quiche_apps::client] got 1104 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:57.188158256Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx pkt Short dcid=78dc2f12eeb374f1640d9a71410f3e6deb36ae16 key_phase=false len=1083 pn=2897098 
[2025-09-11T16:44:57.188167964Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm ACK delay=119 blocks=[4..8] ecn_counts=None
[2025-09-11T16:44:57.188179475Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 packet newly acked 8
[2025-09-11T16:44:57.188185126Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm STREAM id=0 off=62 len=1027 fin=true
[2025-09-11T16:44:57.188190256Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm PADDING len=28
[2025-09-11T16:44:57.188207578Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 1104 bytes
[2025-09-11T16:44:57.188212868Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:57.188216394Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:57.188220041Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 stream id 0 is readable
[2025-09-11T16:44:57.188225632Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 0
[2025-09-11T16:44:57.188230571Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 0
[2025-09-11T16:44:57.188235140Z TRACE quiche::h3::stream] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 read 1 bytes on stream 0
[2025-09-11T16:44:57.188238486Z TRACE quiche::h3] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 rx frm DATA stream=0 wire_payload_len=1024
[2025-09-11T16:44:57.188246701Z DEBUG quiche_apps::common] got 1024 bytes of response data on stream 0
[2025-09-11T16:44:57.188250639Z DEBUG quiche_apps::common] 1/1 responses received
[2025-09-11T16:44:57.188254055Z INFO  quiche_apps::common] 1/1 response(s) received in 3.012670324s, closing...
[2025-09-11T16:44:57.188264335Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx pkt Short dcid=40000054702ea919 key_phase=false len=11 pn=17 
[2025-09-11T16:44:57.188267951Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-09-11T16:44:57.188276497Z TRACE quiche::recovery::congestion::recovery] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 timer=2.537327094s latest_rtt=1.470132496s srtt=1.499937446s min_rtt=1.470132496s rttvar=253.101765ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 754, delivered_time: Instant { tv_sec: 488, tv_nsec: 891186897 }, first_sent_time: Instant { tv_sec: 488, tv_nsec: 891186897 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 8, rate_sample: RateSample { bandwidth: 2.18 Kbps, is_app_limited: true, interval: 1.542575599s, delivered: 421, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 487, tv_nsec: 388116738 }), send_elapsed: 1.542575599s, ack_elapsed: 1.504010773s, rtt: 1.470132496s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11250, last_update: Instant { tv_sec: 488, tv_nsec: 892230364 }, next_time: Instant { tv_sec: 488, tv_nsec: 891186897 }, max_datagram_size: 1350, last_packet_size: None, iv: 3.288889ms, 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-09-11T16:44:57.188315080Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:40666 to 193.167.100.100:443
[2025-09-11T16:44:57.188319157Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:44:58.689060780Z TRACE quiche_apps::client] got 53 bytes from 193.167.100.100:443 to 0.0.0.0:40666
[2025-09-11T16:44:58.689090976Z TRACE quiche_apps::client] 0.0.0.0:40666: processed 53 bytes
[2025-09-11T16:44:58.689101146Z TRACE quiche_apps::client] 0.0.0.0:40666: recv() would block
[2025-09-11T16:44:58.689108059Z TRACE quiche_apps::client] done reading
[2025-09-11T16:44:58.689119159Z TRACE quiche_apps::client] 0.0.0.0:40666 -> 193.167.100.100:443: done writing
[2025-09-11T16:45:04.732226172Z TRACE quiche_apps::client] timed out
[2025-09-11T16:45:04.732265606Z TRACE quiche] 78dc2f12eeb374f1640d9a71410f3e6deb36ae16 draining timeout expired
[2025-09-11T16:45:04.732324517Z TRACE quiche_apps::client] done reading
[2025-09-11T16:45:04.732334806Z INFO  quiche_apps::client] connection closed, recv=13 sent=18 lost=0 retrans=1 sent_bytes=1575 recv_bytes=6421 lost_bytes=0 [local_addr=0.0.0.0:40666 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=13 sent=18 lost=0 retrans=1 rtt=1.499937446s min_rtt=Some(1.470132496s) rttvar=253.101765ms cwnd=13500 sent_bytes=1575 recv_bytes=6421 lost_bytes=0 stream_retrans_bytes=266 pmtu=1350 delivery_rate=272]