[2025-04-30T09:12:15.717753048Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-04-30T09:12:15.718046117Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:56940 with scid 88f450aa7e7129b9fe968363226660ec8c6a2c91 [2025-04-30T09:12:15.718089138Z TRACE quiche::tls] 88f450aa7e7129b9fe968363226660ec8c6a2c91 write message lvl=Initial len=266 [2025-04-30T09:12:15.718108394Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Initial version=1 dcid=af9171afb5d8a3022bceab5a120dc9e9 scid=88f450aa7e7129b9fe968363226660ec8c6a2c91 len=270 pn=0 [2025-04-30T09:12:15.718111450Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm CRYPTO off=0 len=266 [2025-04-30T09:12:15.718125446Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=998.927384ms 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: 292, tv_nsec: 377517330 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, 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: 292, tv_nsec: 377517330 }, next_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, 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:12:15.718195638Z TRACE quiche_apps::client] written 1200 [2025-04-30T09:12:16.718252250Z TRACE quiche_apps::client] timed out [2025-04-30T09:12:16.718281555Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 loss detection timeout expired [2025-04-30T09:12:16.718289550Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=997.762649ms 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: 292, tv_nsec: 377517330 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, 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: 292, tv_nsec: 377517330 }, next_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, 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:12:16.718312703Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:16.718353139Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Initial version=1 dcid=af9171afb5d8a3022bceab5a120dc9e9 scid=88f450aa7e7129b9fe968363226660ec8c6a2c91 len=270 pn=1 [2025-04-30T09:12:16.718357487Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm CRYPTO off=0 len=266 [2025-04-30T09:12:16.718375621Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=1.997954715s 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: 292, tv_nsec: 377517330 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, 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: 293, tv_nsec: 377794595 }, next_time: Instant { tv_sec: 293, tv_nsec: 377794595 }, 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:12:16.718484075Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:56940 to 193.167.100.100:443 [2025-04-30T09:12:16.718495897Z TRACE quiche_apps::client] 0.0.0.0:56940 -> 193.167.100.100:443: done writing [2025-04-30T09:12:17.227639809Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:17.227677720Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx pkt Initial version=1 dcid=88f450aa7e7129b9fe968363226660ec8c6a2c91 scid=0000000000002001109270d9c3982c3864744762 token= len=116 pn=0 [2025-04-30T09:12:17.227697588Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None [2025-04-30T09:12:17.227711554Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 packet newly acked 0 [2025-04-30T09:12:17.227718998Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm CRYPTO off=0 len=90 [2025-04-30T09:12:17.227841538Z TRACE quiche::tls] 88f450aa7e7129b9fe968363226660ec8c6a2c91 set write secret lvl=Handshake [2025-04-30T09:12:17.227854502Z TRACE quiche::tls] 88f450aa7e7129b9fe968363226660ec8c6a2c91 set read secret lvl=Handshake [2025-04-30T09:12:17.227895549Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx pkt Handshake version=1 dcid=88f450aa7e7129b9fe968363226660ec8c6a2c91 scid=0000000000002001109270d9c3982c3864744762 len=985 pn=0 [2025-04-30T09:12:17.227902612Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm CRYPTO off=0 len=717 [2025-04-30T09:12:17.228142903Z TRACE quiche::tls] 88f450aa7e7129b9fe968363226660ec8c6a2c91 write message lvl=Handshake len=36 [2025-04-30T09:12:17.228147682Z TRACE quiche::tls] 88f450aa7e7129b9fe968363226660ec8c6a2c91 set write secret lvl=OneRTT [2025-04-30T09:12:17.228155988Z TRACE quiche::tls] 88f450aa7e7129b9fe968363226660ec8c6a2c91 set read secret lvl=OneRTT [2025-04-30T09:12:17.228174873Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 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(af9171afb5d8a3022bceab5a120dc9e9), max_idle_timeout: 75000, stateless_reset_token: Some(263550661093942243250984406549426138354), 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(0000000000002001109270d9c3982c3864744762), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-04-30T09:12:17.228189952Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm PADDING len=247 [2025-04-30T09:12:17.228206593Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 1200 bytes [2025-04-30T09:12:17.228212263Z TRACE quiche_apps::client] 0.0.0.0:56940: recv() would block [2025-04-30T09:12:17.228215529Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:17.228240547Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm SETTINGS stream=2 len=18 [2025-04-30T09:12:17.228259432Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm GREASE stream=0 len=0 [2025-04-30T09:12:17.228267036Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm GREASE stream=0 len=18 [2025-04-30T09:12:17.228275642Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm HEADERS stream=0 len=30 fin=true [2025-04-30T09:12:17.228283507Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /ntpcxbtdmz", "user-agent: quiche"] [2025-04-30T09:12:17.228363237Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Initial version=1 dcid=0000000000002001109270d9c3982c3864744762 scid=88f450aa7e7129b9fe968363226660ec8c6a2c91 len=6 pn=2 [2025-04-30T09:12:17.228367244Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm ACK delay=86 blocks=[0..0] ecn_counts=None [2025-04-30T09:12:17.228376071Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=4.018807253s latest_rtt=1.509617686s srtt=1.509617686s min_rtt=1.509617686s rttvar=754.808843ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 293, tv_nsec: 887135016 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.509617686s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 292, tv_nsec: 377517330 }), send_elapsed: 0ns, ack_elapsed: 1.509617686s, rtt: 1.509617686s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11178, last_update: Instant { tv_sec: 293, tv_nsec: 887636217 }, next_time: Instant { tv_sec: 293, tv_nsec: 887636257 }, 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:12:17.228397481Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Handshake version=1 dcid=0000000000002001109270d9c3982c3864744762 scid=88f450aa7e7129b9fe968363226660ec8c6a2c91 len=45 pn=3 [2025-04-30T09:12:17.228400216Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm ACK delay=62 blocks=[0..0] ecn_counts=None [2025-04-30T09:12:17.228402711Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm CRYPTO off=0 len=36 [2025-04-30T09:12:17.228408802Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=4.018774852s latest_rtt=1.509617686s srtt=1.509617686s min_rtt=1.509617686s rttvar=754.808843ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=444 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 293, tv_nsec: 887135016 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.509617686s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 292, tv_nsec: 377517330 }), send_elapsed: 0ns, ack_elapsed: 1.509617686s, rtt: 1.509617686s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11178, last_update: Instant { tv_sec: 293, tv_nsec: 887817728 }, next_time: Instant { tv_sec: 293, tv_nsec: 887636257 }, max_datagram_size: 1350, last_packet_size: None, iv: 9.93022ms, 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:12:17.228424211Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 dropped epoch 0 state [2025-04-30T09:12:17.228429270Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13389 out_len=1166 left=1128 [2025-04-30T09:12:17.228440592Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Short dcid=0000000000002001109270d9c3982c3864744762 key_phase=false len=1128 pn=4 [2025-04-30T09:12:17.228443337Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[5d, ea, 0f, 57, 28, e0, fd, 10, 0a, dd, e5, 85, ad, 6b, b5, eb, 43, 44, 9e, 1d] reset_token=[13, 42, 32, 5a, 70, 82, 16, ab, 38, c3, b4, ba, 2f, 68, 8f, a5] [2025-04-30T09:12:17.228449899Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm STREAM id=2 off=0 len=19 fin=false [2025-04-30T09:12:17.228452474Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm PADDING len=1064 [2025-04-30T09:12:17.228458706Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=4.528748081s latest_rtt=1.509617686s srtt=1.509617686s min_rtt=1.509617686s rttvar=754.808843ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1277 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 293, tv_nsec: 887135016 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.509617686s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 292, tv_nsec: 377517330 }), send_elapsed: 0ns, ack_elapsed: 1.509617686s, rtt: 1.509617686s } } pacer=Pacer { enabled: true, capacity: 13500, used: 1166, rate: 11178, last_update: Instant { tv_sec: 293, tv_nsec: 887817728 }, next_time: Instant { tv_sec: 293, tv_nsec: 897747948 }, max_datagram_size: 1350, last_packet_size: Some(1166), 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:12:17.228516524Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:56940 to 193.167.100.100:443 [2025-04-30T09:12:17.228527224Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12223 out_len=1350 left=1312 [2025-04-30T09:12:17.228532725Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Short dcid=0000000000002001109270d9c3982c3864744762 key_phase=false len=6 pn=5 [2025-04-30T09:12:17.228536622Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm STREAM id=6 off=0 len=1 fin=false [2025-04-30T09:12:17.228566498Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=4.528639668s latest_rtt=1.509617686s srtt=1.509617686s min_rtt=1.509617686s rttvar=754.808843ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 293, tv_nsec: 887135016 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.509617686s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 292, tv_nsec: 377517330 }), send_elapsed: 0ns, ack_elapsed: 1.509617686s, rtt: 1.509617686s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11178, last_update: Instant { tv_sec: 293, tv_nsec: 887987045 }, next_time: Instant { tv_sec: 293, tv_nsec: 897747948 }, max_datagram_size: 1350, last_packet_size: None, iv: 108.248345ms, 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:12:17.228614689Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56940 to 193.167.100.100:443 [2025-04-30T09:12:17.228625980Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12179 out_len=1350 left=1312 [2025-04-30T09:12:17.228631680Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Short dcid=0000000000002001109270d9c3982c3864744762 key_phase=false len=6 pn=6 [2025-04-30T09:12:17.228635958Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm STREAM id=10 off=0 len=1 fin=false [2025-04-30T09:12:17.228662027Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=4.528544439s latest_rtt=1.509617686s srtt=1.509617686s min_rtt=1.509617686s rttvar=754.808843ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1365 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 293, tv_nsec: 887135016 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.509617686s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 292, tv_nsec: 377517330 }), send_elapsed: 0ns, ack_elapsed: 1.509617686s, rtt: 1.509617686s } } pacer=Pacer { enabled: true, capacity: 13500, used: 44, rate: 11178, last_update: Instant { tv_sec: 293, tv_nsec: 887987045 }, next_time: Instant { tv_sec: 294, tv_nsec: 5996293 }, 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:12:17.228698435Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56940 to 193.167.100.100:443 [2025-04-30T09:12:17.228708865Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12135 out_len=1350 left=1312 [2025-04-30T09:12:17.228715137Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Short dcid=0000000000002001109270d9c3982c3864744762 key_phase=false len=73 pn=7 [2025-04-30T09:12:17.228719535Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm STREAM id=0 off=0 len=68 fin=true [2025-04-30T09:12:17.228729324Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=4.528477023s latest_rtt=1.509617686s srtt=1.509617686s min_rtt=1.509617686s rttvar=754.808843ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1476 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 293, tv_nsec: 887135016 }, first_sent_time: Instant { tv_sec: 292, tv_nsec: 377517330 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 220, is_app_limited: true, interval: 1.509617686s, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 292, tv_nsec: 377517330 }), send_elapsed: 0ns, ack_elapsed: 1.509617686s, rtt: 1.509617686s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11178, last_update: Instant { tv_sec: 293, tv_nsec: 888168175 }, next_time: Instant { tv_sec: 294, tv_nsec: 5996293 }, max_datagram_size: 1350, last_packet_size: None, iv: 13.866524ms, 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:12:17.228768958Z TRACE quiche_apps::client] written 111 bytes from 0.0.0.0:56940 to 193.167.100.100:443 [2025-04-30T09:12:17.228776432Z TRACE quiche_apps::client] 0.0.0.0:56940 -> 193.167.100.100:443: done writing [2025-04-30T09:12:18.221437218Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:18.221474558Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 dropped invalid packet [2025-04-30T09:12:18.221480649Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 1200 bytes [2025-04-30T09:12:18.221486300Z TRACE quiche_apps::client] 0.0.0.0:56940: recv() would block [2025-04-30T09:12:18.221490197Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:18.221500987Z TRACE quiche_apps::client] 0.0.0.0:56940 -> 193.167.100.100:443: done writing [2025-04-30T09:12:18.732576973Z TRACE quiche_apps::client] got 890 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:18.732619643Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx pkt Short dcid=88f450aa7e7129b9fe968363226660ec8c6a2c91 key_phase=false len=869 pn=0 [2025-04-30T09:12:18.732636796Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm ACK delay=0 blocks=[4..4] ecn_counts=None [2025-04-30T09:12:18.732647055Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 packet newly acked 4 [2025-04-30T09:12:18.732655030Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm CRYPTO off=0 len=540 [2025-04-30T09:12:18.732690987Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm HANDSHAKE_DONE [2025-04-30T09:12:18.732698231Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 dropped epoch 1 state [2025-04-30T09:12:18.732705715Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 20, 01, 41, 30, a1, 0b, a9, c4, ff, 7a, a3, 21, 0a, 91] reset_token=[57, 83, 65, 07, bf, 0d, c9, cc, e5, 85, af, d2, ae, 35, ea, 69] [2025-04-30T09:12:18.732716014Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 20, 01, 32, 3e, e0, 2e, f2, 10, d2, 70, 85, a0, a9, b4] reset_token=[3e, 6d, f9, 63, 45, 83, fe, de, 8f, b5, 25, f0, b4, 11, f8, ba] [2025-04-30T09:12:18.732723208Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 20, 01, 39, 72, 6f, 60, 8d, 05, 1a, 57, de, 45, 14, 6e] reset_token=[93, 36, 0e, f6, 93, c6, 1c, fe, e9, 6a, 45, cb, ec, f2, d2, 2e] [2025-04-30T09:12:18.732729700Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 20, 01, ce, 3d, 88, 83, f7, 36, 81, 3c, f1, d2, c7, bd] reset_token=[89, 52, 84, 47, cc, a5, 92, 39, 57, a2, 68, 6a, 52, 89, 6b, db] [2025-04-30T09:12:18.732736382Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 20, 01, af, 6b, b3, b3, 74, 2f, d7, 99, e7, fa, 67, 76] reset_token=[2c, 8e, 33, df, ce, 73, 97, 98, 81, 20, 2c, a3, fa, 84, 27, f9] [2025-04-30T09:12:18.732743385Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 20, 01, 1b, e2, 5e, b6, 9e, 03, c9, cb, 0f, 61, ab, a2] reset_token=[5e, a2, df, 6b, 0a, 92, ae, b6, 09, 9c, 17, 07, ca, 85, 9c, f8] [2025-04-30T09:12:18.732750278Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[00, 00, 00, 00, 00, 00, 20, 01, 1a, 16, 87, 26, 22, f7, ca, 74, e7, bb, 47, 79] reset_token=[e6, 04, 06, 52, 9f, 82, b9, bd, 83, 94, a2, 06, 64, 45, ce, 60] [2025-04-30T09:12:18.732755608Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm STREAM id=3 off=0 len=1 fin=false [2025-04-30T09:12:18.732774925Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm STREAM id=3 off=1 len=8 fin=false [2025-04-30T09:12:18.732778672Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm STREAM id=7 off=0 len=1 fin=false [2025-04-30T09:12:18.732806454Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 890 bytes [2025-04-30T09:12:18.732813868Z TRACE quiche_apps::client] got 43 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:18.732818066Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx pkt Short dcid=88f450aa7e7129b9fe968363226660ec8c6a2c91 key_phase=false len=22 pn=1 [2025-04-30T09:12:18.732823446Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm ACK delay=0 blocks=[4..6] ecn_counts=None [2025-04-30T09:12:18.732827203Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 packet newly acked 5 [2025-04-30T09:12:18.732830349Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 packet newly acked 6 [2025-04-30T09:12:18.732839476Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 43 bytes [2025-04-30T09:12:18.732844215Z TRACE quiche_apps::client] 0.0.0.0:56940: recv() would block [2025-04-30T09:12:18.732847391Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:18.732851729Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 stream id 3 is readable [2025-04-30T09:12:18.732858521Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 3 [2025-04-30T09:12:18.732864723Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 open peer's control stream 3 [2025-04-30T09:12:18.732869011Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 3 [2025-04-30T09:12:18.732872879Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 3 [2025-04-30T09:12:18.732876906Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 6 bytes on stream 3 [2025-04-30T09:12:18.732880603Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 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:12:18.732890452Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 stream id 7 is readable [2025-04-30T09:12:18.732894549Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 7 [2025-04-30T09:12:18.732907624Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13389 out_len=1350 left=1307 [2025-04-30T09:12:18.732913635Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Short dcid=0000000000002001109270d9c3982c3864744762 key_phase=false len=5 pn=8 [2025-04-30T09:12:18.732916881Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm ACK delay=11 blocks=[0..1] ecn_counts=None [2025-04-30T09:12:18.732925988Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=1.844514205s latest_rtt=1.38628483s srtt=1.49252899s min_rtt=1.38628483s rttvar=457.801882ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=111 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1587, delivered_time: Instant { tv_sec: 295, tv_nsec: 392281123 }, first_sent_time: Instant { tv_sec: 293, tv_nsec: 897747948 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 6, rate_sample: RateSample { delivery_rate: 824, is_app_limited: true, interval: 1.520230618s, delivered: 1254, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 293, tv_nsec: 887135016 }), send_elapsed: 1.520230618s, ack_elapsed: 1.504942966s, rtt: 1.494330034s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11306, last_update: Instant { tv_sec: 295, tv_nsec: 392365471 }, next_time: Instant { tv_sec: 295, tv_nsec: 406231995 }, 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:12:18.733002091Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:56940 to 193.167.100.100:443 [2025-04-30T09:12:18.733011499Z TRACE quiche_apps::client] 0.0.0.0:56940 -> 193.167.100.100:443: done writing [2025-04-30T09:12:18.734363665Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:18.734376640Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx pkt Short dcid=88f450aa7e7129b9fe968363226660ec8c6a2c91 key_phase=false len=1179 pn=2 [2025-04-30T09:12:18.734383883Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm ACK delay=0 blocks=[4..7] ecn_counts=None [2025-04-30T09:12:18.734387650Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 packet newly acked 7 [2025-04-30T09:12:18.734392630Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm STREAM id=0 off=0 len=1152 fin=false [2025-04-30T09:12:18.734404562Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 1200 bytes [2025-04-30T09:12:18.734409782Z TRACE quiche_apps::client] got 64 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:18.734412978Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx pkt Short dcid=88f450aa7e7129b9fe968363226660ec8c6a2c91 key_phase=false len=43 pn=3 [2025-04-30T09:12:18.734416976Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm STREAM id=0 off=1152 len=16 fin=false [2025-04-30T09:12:18.734419901Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm STREAM id=0 off=1168 len=0 fin=true [2025-04-30T09:12:18.734425201Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 64 bytes [2025-04-30T09:12:18.734428727Z TRACE quiche_apps::client] 0.0.0.0:56940: recv() would block [2025-04-30T09:12:18.734431172Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:18.734433847Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 stream id 0 is readable [2025-04-30T09:12:18.734440560Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 0 [2025-04-30T09:12:18.734444467Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 0 [2025-04-30T09:12:18.734447653Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 0 [2025-04-30T09:12:18.734451129Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 138 bytes on stream 0 [2025-04-30T09:12:18.734453644Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm HEADERS stream=0 payload_len=138 [2025-04-30T09:12:18.734456810Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0 [2025-04-30T09:12:18.734459345Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true [2025-04-30T09:12:18.734463583Z 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:12:18.734469564Z 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, 50, 58, 49, 55, 32, 71, 77, 84] [2025-04-30T09:12:18.734474714Z 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:12:18.734478020Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[49, 48, 50, 52] [2025-04-30T09:12:18.734480956Z 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, 50, 58, 49, 52, 32, 71, 77, 84] [2025-04-30T09:12:18.734485444Z TRACE quiche::h3::qpack::decoder] Literal Without Name Reference name=[101, 116, 97, 103] value=[34, 54, 56, 49, 49, 101, 57, 54, 101, 45, 52, 48, 48, 34] [2025-04-30T09:12:18.734495332Z 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:12:18.734499200Z 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:12:18.734509479Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "nginx/1.27.0"), ("date", "Wed, 30 Apr 2025 09:12:17 GMT"), ("content-type", "application/octet-stream"), ("content-length", "1024"), ("last-modified", "Wed, 30 Apr 2025 09:12:14 GMT"), ("etag", "\"6811e96e-400\""), ("alt-svc", "hq-29=\":443\""), ("accept-ranges", "bytes")] on stream id 0 [2025-04-30T09:12:18.734516392Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 stream id 0 is readable [2025-04-30T09:12:18.734519688Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 0 [2025-04-30T09:12:18.734522814Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 0 [2025-04-30T09:12:18.734525649Z TRACE quiche::h3::stream] 88f450aa7e7129b9fe968363226660ec8c6a2c91 read 1 bytes on stream 0 [2025-04-30T09:12:18.734527933Z TRACE quiche::h3] 88f450aa7e7129b9fe968363226660ec8c6a2c91 rx frm DATA stream=0 wire_payload_len=1024 [2025-04-30T09:12:18.734534766Z DEBUG quiche_apps::common] got 1024 bytes of response data on stream 0 [2025-04-30T09:12:18.734559192Z DEBUG quiche_apps::common] 1/1 responses received [2025-04-30T09:12:18.734563591Z INFO quiche_apps::common] 1/1 response(s) received in 3.016362402s, closing... [2025-04-30T09:12:18.734572176Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1312 [2025-04-30T09:12:18.734576134Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx pkt Short dcid=0000000000002001109270d9c3982c3864744762 key_phase=false len=11 pn=9 [2025-04-30T09:12:18.734578749Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-04-30T09:12:18.734586644Z TRACE quiche::recovery::congestion::recovery] 88f450aa7e7129b9fe968363226660ec8c6a2c91 timer=2.982517035s latest_rtt=1.387842181s srtt=1.479443138s min_rtt=1.38628483s rttvar=369.523113ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1698, delivered_time: Instant { tv_sec: 295, tv_nsec: 406231995 }, first_sent_time: Instant { tv_sec: 295, tv_nsec: 406231995 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 7, rate_sample: RateSample { delivery_rate: 897, is_app_limited: true, interval: 1.520230618s, delivered: 1365, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 293, tv_nsec: 887135016 }), send_elapsed: 1.520230618s, ack_elapsed: 1.504942966s, rtt: 1.494330034s } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 11406, last_update: Instant { tv_sec: 295, tv_nsec: 394032258 }, next_time: Instant { tv_sec: 295, tv_nsec: 406231995 }, max_datagram_size: 1350, last_packet_size: None, iv: 4.295985ms, 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:12:18.734628963Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:56940 to 193.167.100.100:443 [2025-04-30T09:12:18.734636096Z TRACE quiche_apps::client] 0.0.0.0:56940 -> 193.167.100.100:443: done writing [2025-04-30T09:12:18.832040097Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:18.832060235Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 1350 bytes [2025-04-30T09:12:18.832065995Z TRACE quiche_apps::client] 0.0.0.0:56940: recv() would block [2025-04-30T09:12:18.832079591Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:18.832087085Z TRACE quiche_apps::client] 0.0.0.0:56940 -> 193.167.100.100:443: done writing [2025-04-30T09:12:19.854334161Z TRACE quiche_apps::client] got 41 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:19.854363015Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 41 bytes [2025-04-30T09:12:19.854371170Z TRACE quiche_apps::client] got 41 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:19.854376670Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 41 bytes [2025-04-30T09:12:19.854383233Z TRACE quiche_apps::client] 0.0.0.0:56940: recv() would block [2025-04-30T09:12:19.854388062Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:19.854398051Z TRACE quiche_apps::client] 0.0.0.0:56940 -> 193.167.100.100:443: done writing [2025-04-30T09:12:19.855555733Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:56940 [2025-04-30T09:12:19.855568296Z TRACE quiche_apps::client] 0.0.0.0:56940: processed 1350 bytes [2025-04-30T09:12:19.855573767Z TRACE quiche_apps::client] 0.0.0.0:56940: recv() would block [2025-04-30T09:12:19.855577564Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:19.855587232Z TRACE quiche_apps::client] 0.0.0.0:56940 -> 193.167.100.100:443: done writing [2025-04-30T09:12:27.615397841Z TRACE quiche_apps::client] timed out [2025-04-30T09:12:27.615437406Z TRACE quiche] 88f450aa7e7129b9fe968363226660ec8c6a2c91 draining timeout expired [2025-04-30T09:12:27.615497789Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:27.615504321Z INFO quiche_apps::client] connection closed, recv=6 sent=10 lost=0 retrans=1 sent_bytes=2307 recv_bytes=3397 lost_bytes=0 [local_addr=0.0.0.0:56940 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=6 sent=10 lost=0 retrans=1 rtt=1.479443138s min_rtt=Some(1.38628483s) rttvar=369.523113ms cwnd=13500 sent_bytes=2307 recv_bytes=3397 lost_bytes=0 stream_retrans_bytes=266 pmtu=1350 delivery_rate=897]