[2025-04-30T09:11:38.715748419Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T09:11:38.716035167Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:39530 with scid 26be7d9570e59aa1af53d7cba6d3054693950304
[2025-04-30T09:11:38.716078058Z TRACE quiche::tls] 26be7d9570e59aa1af53d7cba6d3054693950304 write message lvl=Initial len=266
[2025-04-30T09:11:38.716097234Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Initial version=1 dcid=ab4f030ef3acd4291f66b2c9e33b62ac scid=26be7d9570e59aa1af53d7cba6d3054693950304 len=270 pn=0 
[2025-04-30T09:11:38.716100449Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm CRYPTO off=0 len=266
[2025-04-30T09:11:38.716113985Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=998.927725ms 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: 255, tv_nsec: 375506229 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 375506229 }, 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: 255, tv_nsec: 375506229 }, next_time: Instant { tv_sec: 255, tv_nsec: 375506229 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.716174158Z TRACE quiche_apps::client] written 1200
[2025-04-30T09:11:38.755426822Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:39530
[2025-04-30T09:11:38.755447120Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx pkt Initial version=1 dcid=26be7d9570e59aa1af53d7cba6d3054693950304 scid=0000000000000001753f401ab1e258946f6e0769 token= len=116 pn=0 
[2025-04-30T09:11:38.755460225Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T09:11:38.755466506Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 packet newly acked 0
[2025-04-30T09:11:38.755472898Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm CRYPTO off=0 len=90
[2025-04-30T09:11:38.755606479Z TRACE quiche::tls] 26be7d9570e59aa1af53d7cba6d3054693950304 set write secret lvl=Handshake
[2025-04-30T09:11:38.755618722Z TRACE quiche::tls] 26be7d9570e59aa1af53d7cba6d3054693950304 set read secret lvl=Handshake
[2025-04-30T09:11:38.755651704Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx pkt Handshake version=1 dcid=26be7d9570e59aa1af53d7cba6d3054693950304 scid=0000000000000001753f401ab1e258946f6e0769 len=985 pn=0 
[2025-04-30T09:11:38.755658858Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm CRYPTO off=0 len=718
[2025-04-30T09:11:38.755941949Z TRACE quiche::tls] 26be7d9570e59aa1af53d7cba6d3054693950304 write message lvl=Handshake len=36
[2025-04-30T09:11:38.755947970Z TRACE quiche::tls] 26be7d9570e59aa1af53d7cba6d3054693950304 set write secret lvl=OneRTT
[2025-04-30T09:11:38.755956817Z TRACE quiche::tls] 26be7d9570e59aa1af53d7cba6d3054693950304 set read secret lvl=OneRTT
[2025-04-30T09:11:38.755974901Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 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(ab4f030ef3acd4291f66b2c9e33b62ac), max_idle_timeout: 75000, stateless_reset_token: Some(259522933877293856786685289678417812916), max_udp_payload_size: 65527, initial_max_data: 8585216, initial_max_stream_data_bidi_local: 65536, initial_max_stream_data_bidi_remote: 65536, initial_max_stream_data_uni: 65536, initial_max_streams_bidi: 128, initial_max_streams_uni: 3, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 2, initial_source_connection_id: Some(0000000000000001753f401ab1e258946f6e0769), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T09:11:38.755997293Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm PADDING len=246
[2025-04-30T09:11:38.756015457Z TRACE quiche_apps::client] 0.0.0.0:39530: processed 1200 bytes
[2025-04-30T09:11:38.756021228Z TRACE quiche_apps::client] 0.0.0.0:39530: recv() would block
[2025-04-30T09:11:38.756024353Z TRACE quiche_apps::client] done reading
[2025-04-30T09:11:38.756040905Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm SETTINGS stream=2 len=18
[2025-04-30T09:11:38.756059900Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm GREASE stream=0 len=0
[2025-04-30T09:11:38.756067164Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm GREASE stream=0 len=18
[2025-04-30T09:11:38.756075570Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm HEADERS stream=0 len=30 fin=true
[2025-04-30T09:11:38.756083044Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /wfdbqgirnw", "user-agent: quiche"]
[2025-04-30T09:11:38.756151382Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Initial version=1 dcid=0000000000000001753f401ab1e258946f6e0769 scid=26be7d9570e59aa1af53d7cba6d3054693950304 len=6 pn=1 
[2025-04-30T09:11:38.756156021Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm ACK delay=88 blocks=[0..0] ecn_counts=None
[2025-04-30T09:11:38.756165869Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=117.479198ms latest_rtt=39.400802ms srtt=39.400802ms min_rtt=39.400802ms rttvar=19.700401ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8451, is_app_limited: true, interval: 39.400802ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 255, tv_nsec: 375506229 }), send_elapsed: 0ns, ack_elapsed: 39.400802ms, rtt: 39.400802ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 428290, last_update: Instant { tv_sec: 255, tv_nsec: 415436825 }, next_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.756188421Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Handshake version=1 dcid=0000000000000001753f401ab1e258946f6e0769 scid=26be7d9570e59aa1af53d7cba6d3054693950304 len=46 pn=2 
[2025-04-30T09:11:38.756192179Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm ACK delay=67 blocks=[0..0] ecn_counts=None
[2025-04-30T09:11:38.756195575Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm CRYPTO off=0 len=36
[2025-04-30T09:11:38.756203189Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=118.140771ms latest_rtt=39.400802ms srtt=39.400802ms min_rtt=39.400802ms rttvar=19.700401ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=112 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8451, is_app_limited: true, interval: 39.400802ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 255, tv_nsec: 375506229 }), send_elapsed: 0ns, ack_elapsed: 39.400802ms, rtt: 39.400802ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 428290, last_update: Instant { tv_sec: 255, tv_nsec: 415605783 }, next_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, max_datagram_size: 1350, last_packet_size: None, iv: 261.505µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.756222576Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 dropped epoch 0 state
[2025-04-30T09:11:38.756228306Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13388 out_len=1165 left=1127
[2025-04-30T09:11:38.756241772Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Short dcid=0000000000000001753f401ab1e258946f6e0769 key_phase=false len=1127 pn=3 
[2025-04-30T09:11:38.756244998Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e5, 0b, d9, a7, 54, ae, 89, 1e, 78, 37, 8a, 2c, 6e, 25, 74, 02, 60, 58, f7, c1] reset_token=[d6, 06, 06, b7, 8c, 3e, 63, d7, f5, 52, 2b, 4a, a3, 8b, ee, 42]
[2025-04-30T09:11:38.756252933Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T09:11:38.756256219Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm PADDING len=1063
[2025-04-30T09:11:38.756263933Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=118.080177ms latest_rtt=39.400802ms srtt=39.400802ms min_rtt=39.400802ms rttvar=19.700401ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1277 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8451, is_app_limited: true, interval: 39.400802ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 255, tv_nsec: 375506229 }), send_elapsed: 0ns, ack_elapsed: 39.400802ms, rtt: 39.400802ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1165, rate: 428290, last_update: Instant { tv_sec: 255, tv_nsec: 415605783 }, next_time: Instant { tv_sec: 255, tv_nsec: 415867288 }, max_datagram_size: 1350, last_packet_size: Some(1165), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.756311683Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:39530 to 193.167.100.100:443
[2025-04-30T09:11:38.756320149Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12223 out_len=1350 left=1312
[2025-04-30T09:11:38.756324457Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Short dcid=0000000000000001753f401ab1e258946f6e0769 key_phase=false len=6 pn=4 
[2025-04-30T09:11:38.756327593Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T09:11:38.756334486Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=118.009464ms latest_rtt=39.400802ms srtt=39.400802ms min_rtt=39.400802ms rttvar=19.700401ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8451, is_app_limited: true, interval: 39.400802ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 255, tv_nsec: 375506229 }), send_elapsed: 0ns, ack_elapsed: 39.400802ms, rtt: 39.400802ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 428290, last_update: Instant { tv_sec: 255, tv_nsec: 415780721 }, next_time: Instant { tv_sec: 255, tv_nsec: 415867288 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.822854ms, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.756375302Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:39530 to 193.167.100.100:443
[2025-04-30T09:11:38.756382476Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12179 out_len=1350 left=1312
[2025-04-30T09:11:38.756386673Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Short dcid=0000000000000001753f401ab1e258946f6e0769 key_phase=false len=6 pn=5 
[2025-04-30T09:11:38.756389729Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T09:11:38.756395911Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=117.948109ms latest_rtt=39.400802ms srtt=39.400802ms min_rtt=39.400802ms rttvar=19.700401ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1365 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8451, is_app_limited: true, interval: 39.400802ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 255, tv_nsec: 375506229 }), send_elapsed: 0ns, ack_elapsed: 39.400802ms, rtt: 39.400802ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 44, rate: 428290, last_update: Instant { tv_sec: 255, tv_nsec: 415780721 }, next_time: Instant { tv_sec: 255, tv_nsec: 418690142 }, max_datagram_size: 1350, last_packet_size: Some(44), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.756427230Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:39530 to 193.167.100.100:443
[2025-04-30T09:11:38.756433541Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12135 out_len=1350 left=1312
[2025-04-30T09:11:38.756437529Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Short dcid=0000000000000001753f401ab1e258946f6e0769 key_phase=false len=73 pn=6 
[2025-04-30T09:11:38.756440615Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T09:11:38.756466493Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=117.877477ms latest_rtt=39.400802ms srtt=39.400802ms min_rtt=39.400802ms rttvar=19.700401ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1476 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 415436866 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8451, is_app_limited: true, interval: 39.400802ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 255, tv_nsec: 375506229 }), send_elapsed: 0ns, ack_elapsed: 39.400802ms, rtt: 39.400802ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 428290, last_update: Instant { tv_sec: 255, tv_nsec: 415894976 }, next_time: Instant { tv_sec: 255, tv_nsec: 418690142 }, max_datagram_size: 1350, last_packet_size: None, iv: 361.904µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.756504224Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:39530 to 193.167.100.100:443
[2025-04-30T09:11:38.756509274Z TRACE quiche_apps::client] 0.0.0.0:39530 -> 193.167.100.100:443: done writing
[2025-04-30T09:11:38.790870905Z TRACE quiche_apps::client] got 890 bytes from 193.167.100.100:443 to 0.0.0.0:39530
[2025-04-30T09:11:38.790887616Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx pkt Short dcid=26be7d9570e59aa1af53d7cba6d3054693950304 key_phase=false len=869 pn=0 
[2025-04-30T09:11:38.790897915Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm ACK delay=0 blocks=[3..3] ecn_counts=None
[2025-04-30T09:11:38.790902945Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 packet newly acked 3
[2025-04-30T09:11:38.790908725Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm CRYPTO off=0 len=540
[2025-04-30T09:11:38.790935345Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm HANDSHAKE_DONE
[2025-04-30T09:11:38.790940645Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 dropped epoch 1 state
[2025-04-30T09:11:38.790946937Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 00, 01, 2f, 40, 70, a6, b7, 85, 83, 5d, fa, 4f, 37, 73] reset_token=[f9, 89, df, b3, a9, d8, 56, e1, 45, 9f, fa, dc, ce, 19, fb, 71]
[2025-04-30T09:11:38.790956154Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 00, 01, ed, 81, d1, e8, 89, 64, 6e, 02, 4b, ed, 96, 58] reset_token=[8c, 25, ad, be, e8, 5c, 16, b3, 3f, 41, 0c, d2, 6c, e7, 0b, 8b]
[2025-04-30T09:11:38.790964009Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 00, 01, 22, 4e, eb, c8, 94, db, a4, 10, 16, a3, 4f, 8a] reset_token=[74, 10, 5a, f1, d2, 12, 85, 26, eb, 7d, 3f, 7e, 84, 76, c6, ad]
[2025-04-30T09:11:38.790971153Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 00, 01, 70, 9a, f4, 22, 88, f9, 4f, 5b, 84, 39, 8e, a6] reset_token=[5d, 8c, ca, 79, 67, 22, ed, 61, 74, c5, 35, 01, 40, 4b, 26, e9]
[2025-04-30T09:11:38.790978596Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 00, 01, 95, 76, c8, cf, bb, ac, f0, ad, ba, 08, a7, a2] reset_token=[26, 66, 26, e0, 1c, 56, 9b, 04, 9c, bd, 02, 06, 85, 0f, fe, e4]
[2025-04-30T09:11:38.790986030Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 00, 01, 35, 3e, 9f, d9, b8, 46, ef, 80, e0, 08, c6, be] reset_token=[dc, c3, b4, 79, 63, 34, c8, 55, a9, 37, d2, 61, 4e, 48, 32, 57]
[2025-04-30T09:11:38.790993094Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 00, 01, eb, 26, d7, 82, 2c, c0, fd, bb, 70, 51, a9, a6] reset_token=[90, 31, 8f, 6c, 16, a8, e1, 79, 14, 86, dd, f1, b0, 3b, 0d, 34]
[2025-04-30T09:11:38.791006849Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm STREAM id=3 off=0 len=1 fin=false
[2025-04-30T09:11:38.791011649Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm STREAM id=3 off=1 len=8 fin=false
[2025-04-30T09:11:38.791015426Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T09:11:38.791035143Z TRACE quiche_apps::client] 0.0.0.0:39530: processed 890 bytes
[2025-04-30T09:11:38.791042527Z TRACE quiche_apps::client] got 43 bytes from 193.167.100.100:443 to 0.0.0.0:39530
[2025-04-30T09:11:38.791047085Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx pkt Short dcid=26be7d9570e59aa1af53d7cba6d3054693950304 key_phase=false len=22 pn=1 
[2025-04-30T09:11:38.791053096Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm ACK delay=0 blocks=[3..5] ecn_counts=None
[2025-04-30T09:11:38.791057074Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 packet newly acked 4
[2025-04-30T09:11:38.791060300Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 packet newly acked 5
[2025-04-30T09:11:38.791075037Z TRACE quiche_apps::client] 0.0.0.0:39530: processed 43 bytes
[2025-04-30T09:11:38.791080047Z TRACE quiche_apps::client] 0.0.0.0:39530: recv() would block
[2025-04-30T09:11:38.791083253Z TRACE quiche_apps::client] done reading
[2025-04-30T09:11:38.791086679Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 stream id 3 is readable
[2025-04-30T09:11:38.791093071Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 3
[2025-04-30T09:11:38.791098512Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 open peer's control stream 3
[2025-04-30T09:11:38.791102930Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 3
[2025-04-30T09:11:38.791107118Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 3
[2025-04-30T09:11:38.791111516Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 6 bytes on stream 3
[2025-04-30T09:11:38.791115243Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm SETTINGS max_field_section=None, qpack_max_table=Some(4096), qpack_blocked=Some(128) raw=Some([(1, 4096), (7, 128)]), additional_settings=None stream=3 payload_len=6
[2025-04-30T09:11:38.791124240Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 stream id 7 is readable
[2025-04-30T09:11:38.791128708Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 7
[2025-04-30T09:11:38.791140330Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13389 out_len=1350 left=1307
[2025-04-30T09:11:38.791145009Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Short dcid=0000000000000001753f401ab1e258946f6e0769 key_phase=false len=5 pn=7 
[2025-04-30T09:11:38.791148455Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm ACK delay=11 blocks=[0..1] ecn_counts=None
[2025-04-30T09:11:38.791156701Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=83.170224ms latest_rtt=31.81998ms srtt=37.91519ms min_rtt=31.81998ms rttvar=13.745262ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=111 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1586, delivered_time: Instant { tv_sec: 255, tv_nsec: 450510122 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 415867288 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 5, rate_sample: RateSample { delivery_rate: 35889, is_app_limited: true, interval: 34.912294ms, delivered: 1253, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 255, tv_nsec: 415436866 }), send_elapsed: 430.422µs, ack_elapsed: 34.912294ms, rtt: 34.481872ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 445072, last_update: Instant { tv_sec: 255, tv_nsec: 450599419 }, next_time: Instant { tv_sec: 255, tv_nsec: 450961323 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.791217224Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:39530 to 193.167.100.100:443
[2025-04-30T09:11:38.791224067Z TRACE quiche_apps::client] 0.0.0.0:39530 -> 193.167.100.100:443: done writing
[2025-04-30T09:11:38.791981338Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:39530
[2025-04-30T09:11:38.791993982Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx pkt Short dcid=26be7d9570e59aa1af53d7cba6d3054693950304 key_phase=false len=1179 pn=2 
[2025-04-30T09:11:38.792003420Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm ACK delay=0 blocks=[3..6] ecn_counts=None
[2025-04-30T09:11:38.792008740Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 packet newly acked 6
[2025-04-30T09:11:38.792014040Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm STREAM id=0 off=0 len=1152 fin=false
[2025-04-30T09:11:38.792025181Z TRACE quiche_apps::client] 0.0.0.0:39530: processed 1200 bytes
[2025-04-30T09:11:38.792030531Z TRACE quiche_apps::client] got 64 bytes from 193.167.100.100:443 to 0.0.0.0:39530
[2025-04-30T09:11:38.792033677Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx pkt Short dcid=26be7d9570e59aa1af53d7cba6d3054693950304 key_phase=false len=43 pn=3 
[2025-04-30T09:11:38.792037644Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm STREAM id=0 off=1152 len=16 fin=false
[2025-04-30T09:11:38.792040449Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm STREAM id=0 off=1168 len=0 fin=true
[2025-04-30T09:11:38.792045399Z TRACE quiche_apps::client] 0.0.0.0:39530: processed 64 bytes
[2025-04-30T09:11:38.792048855Z TRACE quiche_apps::client] 0.0.0.0:39530: recv() would block
[2025-04-30T09:11:38.792051230Z TRACE quiche_apps::client] done reading
[2025-04-30T09:11:38.792053885Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 stream id 0 is readable
[2025-04-30T09:11:38.792057562Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 0
[2025-04-30T09:11:38.792060748Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 0
[2025-04-30T09:11:38.792063693Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 0
[2025-04-30T09:11:38.792067350Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 138 bytes on stream 0
[2025-04-30T09:11:38.792069865Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm HEADERS stream=0 payload_len=138
[2025-04-30T09:11:38.792072760Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T09:11:38.792075305Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-04-30T09:11:38.792079413Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[110, 103, 105, 110, 120, 47, 49, 46, 50, 55, 46, 48]
[2025-04-30T09:11:38.792085414Z TRACE quiche::h3::qpack::decoder] Literal name_idx=6 static=true value=[87, 101, 100, 44, 32, 51, 48, 32, 65, 112, 114, 32, 50, 48, 50, 53, 32, 48, 57, 58, 49, 49, 58, 51, 56, 32, 71, 77, 84]
[2025-04-30T09:11:38.792090133Z TRACE quiche::h3::qpack::decoder] Literal name_idx=53 static=true value=[97, 112, 112, 108, 105, 99, 97, 116, 105, 111, 110, 47, 111, 99, 116, 101, 116, 45, 115, 116, 114, 101, 97, 109]
[2025-04-30T09:11:38.792093509Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[49, 48, 50, 52]
[2025-04-30T09:11:38.792096354Z TRACE quiche::h3::qpack::decoder] Literal name_idx=10 static=true value=[87, 101, 100, 44, 32, 51, 48, 32, 65, 112, 114, 32, 50, 48, 50, 53, 32, 48, 57, 58, 49, 49, 58, 51, 54, 32, 71, 77, 84]
[2025-04-30T09:11:38.792105852Z TRACE quiche::h3::qpack::decoder] Literal Without Name Reference name=[101, 116, 97, 103] value=[34, 54, 56, 49, 49, 101, 57, 52, 56, 45, 52, 48, 48, 34]
[2025-04-30T09:11:38.792110451Z TRACE quiche::h3::qpack::decoder] Literal Without Name Reference name=[97, 108, 116, 45, 115, 118, 99] value=[104, 113, 45, 50, 57, 61, 34, 58, 52, 52, 51, 34]
[2025-04-30T09:11:38.792114258Z TRACE quiche::h3::qpack::decoder] Literal Without Name Reference name=[97, 99, 99, 101, 112, 116, 45, 114, 97, 110, 103, 101, 115] value=[98, 121, 116, 101, 115]
[2025-04-30T09:11:38.792123556Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "nginx/1.27.0"), ("date", "Wed, 30 Apr 2025 09:11:38 GMT"), ("content-type", "application/octet-stream"), ("content-length", "1024"), ("last-modified", "Wed, 30 Apr 2025 09:11:36 GMT"), ("etag", "\"6811e948-400\""), ("alt-svc", "hq-29=\":443\""), ("accept-ranges", "bytes")] on stream id 0
[2025-04-30T09:11:38.792130048Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 stream id 0 is readable
[2025-04-30T09:11:38.792133163Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 0
[2025-04-30T09:11:38.792136159Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 0
[2025-04-30T09:11:38.792139065Z TRACE quiche::h3::stream] 26be7d9570e59aa1af53d7cba6d3054693950304 read 1 bytes on stream 0
[2025-04-30T09:11:38.792141469Z TRACE quiche::h3] 26be7d9570e59aa1af53d7cba6d3054693950304 rx frm DATA stream=0 wire_payload_len=1024
[2025-04-30T09:11:38.792147641Z DEBUG quiche_apps::common] got 1024 bytes of response data on stream 0
[2025-04-30T09:11:38.792150476Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T09:11:38.792152921Z INFO  quiche_apps::common] 1/1 response(s) received in 75.974976ms, closing...
[2025-04-30T09:11:38.792160265Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1312
[2025-04-30T09:11:38.792163801Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx pkt Short dcid=0000000000000001753f401ab1e258946f6e0769 key_phase=false len=11 pn=8 
[2025-04-30T09:11:38.792166316Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T09:11:38.792173078Z TRACE quiche::recovery::congestion::recovery] 26be7d9570e59aa1af53d7cba6d3054693950304 timer=108.640282ms latest_rtt=32.766106ms srtt=37.271554ms min_rtt=31.81998ms rttvar=11.596217ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1697, delivered_time: Instant { tv_sec: 255, tv_nsec: 450961323 }, first_sent_time: Instant { tv_sec: 255, tv_nsec: 450961323 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 6, rate_sample: RateSample { delivery_rate: 39069, is_app_limited: true, interval: 34.912294ms, delivered: 1364, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 255, tv_nsec: 415436866 }), send_elapsed: 430.422µs, ack_elapsed: 34.912294ms, rtt: 34.481872ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 452758, last_update: Instant { tv_sec: 255, tv_nsec: 451621057 }, next_time: Instant { tv_sec: 255, tv_nsec: 450961323 }, max_datagram_size: 1350, last_packet_size: None, iv: 108.226µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T09:11:38.792209717Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:39530 to 193.167.100.100:443
[2025-04-30T09:11:38.792213755Z TRACE quiche_apps::client] 0.0.0.0:39530 -> 193.167.100.100:443: done writing
[2025-04-30T09:11:39.043511361Z TRACE quiche_apps::client] timed out
[2025-04-30T09:11:39.043556315Z TRACE quiche] 26be7d9570e59aa1af53d7cba6d3054693950304 draining timeout expired
[2025-04-30T09:11:39.043588676Z TRACE quiche_apps::client] done reading
[2025-04-30T09:11:39.043592964Z INFO  quiche_apps::client] connection closed, recv=6 sent=9 lost=0 retrans=0 sent_bytes=1974 recv_bytes=3397 lost_bytes=0 [local_addr=0.0.0.0:39530 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=6 sent=9 lost=0 retrans=0 rtt=37.271554ms min_rtt=Some(31.81998ms) rttvar=11.596217ms cwnd=13500 sent_bytes=1974 recv_bytes=3397 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=39069]