[2025-04-30T09:12:41.108417592Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-04-30T09:12:41.108734720Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:46977 with scid 3c4caa78f50e75205f3181def2204a9aa1718061 [2025-04-30T09:12:41.108776969Z TRACE quiche::tls] 3c4caa78f50e75205f3181def2204a9aa1718061 write message lvl=Initial len=266 [2025-04-30T09:12:41.108792729Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Initial version=1 dcid=db5eab8166063c535a4eaade69fab7ca scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=270 pn=0 [2025-04-30T09:12:41.108796185Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm CRYPTO off=0 len=266 [2025-04-30T09:12:41.108809611Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=998.933735ms 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: 369, tv_nsec: 811211113 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 811211113 }, 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: 369, tv_nsec: 811211113 }, next_time: Instant { tv_sec: 369, tv_nsec: 811211113 }, 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:41.108869333Z TRACE quiche_apps::client] written 1200 [2025-04-30T09:12:41.146900062Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.146918086Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Initial version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea token= len=116 pn=0 [2025-04-30T09:12:41.146931782Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm ACK delay=53 blocks=[0..0] ecn_counts=None [2025-04-30T09:12:41.146938154Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet newly acked 0 [2025-04-30T09:12:41.146944045Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=0 len=90 [2025-04-30T09:12:41.147048151Z TRACE quiche::tls] 3c4caa78f50e75205f3181def2204a9aa1718061 set write secret lvl=Handshake [2025-04-30T09:12:41.147059502Z TRACE quiche::tls] 3c4caa78f50e75205f3181def2204a9aa1718061 set read secret lvl=Handshake [2025-04-30T09:12:41.147091102Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=985 pn=1 [2025-04-30T09:12:41.147097814Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=0 len=964 [2025-04-30T09:12:41.147116099Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1200 bytes [2025-04-30T09:12:41.147121549Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.147124835Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.147150223Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Initial version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=1 [2025-04-30T09:12:41.147154211Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=29 blocks=[0..0] ecn_counts=None [2025-04-30T09:12:41.147162817Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=114.262256ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442095, last_update: Instant { tv_sec: 369, tv_nsec: 849577775 }, next_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, 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:41.147192693Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=2 [2025-04-30T09:12:41.147196160Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=12 blocks=[1..1] ecn_counts=None [2025-04-30T09:12:41.147202582Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=114.222932ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442095, last_update: Instant { tv_sec: 369, tv_nsec: 849577775 }, next_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, 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:41.147215316Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 dropped epoch 0 state [2025-04-30T09:12:41.147255461Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.147266272Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.147855102Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.147864549Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1151 pn=2 [2025-04-30T09:12:41.147872675Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=964 len=1129 [2025-04-30T09:12:41.147886821Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1200 bytes [2025-04-30T09:12:41.147891260Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.147894436Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.147904795Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=3 [2025-04-30T09:12:41.147913862Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=5 blocks=[1..2] ecn_counts=None [2025-04-30T09:12:41.147921897Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=113.731126ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442095, last_update: Instant { tv_sec: 369, tv_nsec: 849577775 }, next_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, 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:41.147966602Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.147976079Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.148832684Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.148840078Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1151 pn=3 [2025-04-30T09:12:41.148846731Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=2093 len=1129 [2025-04-30T09:12:41.148857331Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1200 bytes [2025-04-30T09:12:41.148860907Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.148863302Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.148871918Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=4 [2025-04-30T09:12:41.148874503Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=3 blocks=[1..3] ecn_counts=None [2025-04-30T09:12:41.148880604Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=112.772549ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442095, last_update: Instant { tv_sec: 369, tv_nsec: 849577775 }, next_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, 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:41.148921060Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.148928685Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.262094004Z TRACE quiche_apps::client] timed out [2025-04-30T09:12:41.262120924Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 loss detection timeout expired [2025-04-30T09:12:41.262126144Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=229.015428ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 442095, last_update: Instant { tv_sec: 369, tv_nsec: 849577775 }, next_time: Instant { tv_sec: 369, tv_nsec: 849577805 }, 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:41.262147825Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.262179765Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=7 pn=5 [2025-04-30T09:12:41.262184073Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=14167 blocks=[1..3] ecn_counts=None [2025-04-30T09:12:41.262189644Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm PING mtu_probe=None [2025-04-30T09:12:41.262206556Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=228.973499ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=73 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 73, rate: 442095, last_update: Instant { tv_sec: 369, tv_nsec: 964624720 }, next_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, max_datagram_size: 1350, last_packet_size: Some(73), 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:41.262294862Z TRACE quiche_apps::client] written 73 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.262305592Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.491591979Z TRACE quiche_apps::client] timed out [2025-04-30T09:12:41.491616014Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 loss detection timeout expired [2025-04-30T09:12:41.491621855Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=228.579759ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=73 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 73, rate: 442095, last_update: Instant { tv_sec: 369, tv_nsec: 964624720 }, next_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, max_datagram_size: 1350, last_packet_size: Some(73), 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:41.491657422Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.491697127Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=9 pn=6 [2025-04-30T09:12:41.491701526Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=42856 blocks=[1..3] ecn_counts=None [2025-04-30T09:12:41.491707357Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm PING mtu_probe=None [2025-04-30T09:12:41.491726402Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=457.985882ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=148 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 75, rate: 442095, last_update: Instant { tv_sec: 370, tv_nsec: 194134117 }, next_time: Instant { tv_sec: 370, tv_nsec: 194134117 }, max_datagram_size: 1350, last_packet_size: Some(75), 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:41.491800733Z TRACE quiche_apps::client] written 75 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.491814519Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=9 pn=7 [2025-04-30T09:12:41.491818145Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=42871 blocks=[1..3] ecn_counts=None [2025-04-30T09:12:41.491821782Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm PING mtu_probe=None [2025-04-30T09:12:41.491828815Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=458.022791ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=223 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 150, rate: 442095, last_update: Instant { tv_sec: 370, tv_nsec: 194134117 }, next_time: Instant { tv_sec: 370, tv_nsec: 194134117 }, max_datagram_size: 1350, last_packet_size: Some(75), 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:41.491871446Z TRACE quiche_apps::client] written 75 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.491877347Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.950391318Z TRACE quiche_apps::client] timed out [2025-04-30T09:12:41.950417868Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 loss detection timeout expired [2025-04-30T09:12:41.950423578Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=457.472469ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=223 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 150, rate: 442095, last_update: Instant { tv_sec: 370, tv_nsec: 194134117 }, next_time: Instant { tv_sec: 370, tv_nsec: 194134117 }, max_datagram_size: 1350, last_packet_size: Some(75), 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:41.950446442Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.950479564Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=9 pn=8 [2025-04-30T09:12:41.950484113Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=100204 blocks=[1..3] ecn_counts=None [2025-04-30T09:12:41.950489753Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm PING mtu_probe=None [2025-04-30T09:12:41.950507747Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=916.038929ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=298 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 75, rate: 442095, last_update: Instant { tv_sec: 370, tv_nsec: 652922855 }, next_time: Instant { tv_sec: 370, tv_nsec: 652922855 }, max_datagram_size: 1350, last_packet_size: Some(75), 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:41.950631139Z TRACE quiche_apps::client] written 75 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.950652109Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=9 pn=9 [2025-04-30T09:12:41.950658291Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=100226 blocks=[1..3] ecn_counts=None [2025-04-30T09:12:41.950664052Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm PING mtu_probe=None [2025-04-30T09:12:41.950677116Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=916.053317ms latest_rtt=38.170472ms srtt=38.170472ms min_rtt=38.170472ms rttvar=19.085236ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=373 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, first_sent_time: Instant { tv_sec: 369, tv_nsec: 964624720 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8724, is_app_limited: true, interval: 38.170472ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 811211113 }), send_elapsed: 0ns, ack_elapsed: 38.170472ms, rtt: 38.170472ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 150, rate: 442095, last_update: Instant { tv_sec: 370, tv_nsec: 652922855 }, next_time: Instant { tv_sec: 370, tv_nsec: 652922855 }, max_datagram_size: 1350, last_packet_size: Some(75), 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:41.950722382Z TRACE quiche_apps::client] written 75 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.950732120Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.982325945Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.982343949Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1151 pn=4 [2025-04-30T09:12:41.982355922Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm ACK delay=5 blocks=[8..9] ecn_counts=None [2025-04-30T09:12:41.982361893Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet newly acked 8 [2025-04-30T09:12:41.982365429Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet newly acked 9 [2025-04-30T09:12:41.982371130Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet 5 lost on epoch 1 [2025-04-30T09:12:41.982374617Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet 6 lost on epoch 1 [2025-04-30T09:12:41.982378103Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet 7 lost on epoch 1 [2025-04-30T09:12:41.982384015Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=3222 len=1124 [2025-04-30T09:12:41.982406637Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1200 bytes [2025-04-30T09:12:41.982411957Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.982423168Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.982436033Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=10 [2025-04-30T09:12:41.982439599Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=11 blocks=[4..4] ecn_counts=None [2025-04-30T09:12:41.982449047Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 684807629 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 652922855 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 652922855 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 652922855 }, max_datagram_size: 1350, last_packet_size: None, iv: 474.727µ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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.982527745Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.982561479Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.983282167Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.983291795Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1151 pn=5 [2025-04-30T09:12:41.983300201Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=4346 len=1129 [2025-04-30T09:12:41.983313586Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1200 bytes [2025-04-30T09:12:41.983317704Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.983320199Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.983329877Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=11 [2025-04-30T09:12:41.983332803Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=4 blocks=[4..5] ecn_counts=None [2025-04-30T09:12:41.983340347Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 684807629 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 9, last_sent_packet: 11, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.983390361Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.983398677Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.984261874Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.984274618Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1151 pn=6 [2025-04-30T09:12:41.984283234Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=5475 len=1129 [2025-04-30T09:12:41.984297661Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1200 bytes [2025-04-30T09:12:41.984301799Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.984304314Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.984313861Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=12 [2025-04-30T09:12:41.984316837Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=4 blocks=[4..6] ecn_counts=None [2025-04-30T09:12:41.984324361Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 684807629 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 9, last_sent_packet: 12, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.984368995Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.984377000Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.985229447Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.985242091Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1151 pn=7 [2025-04-30T09:12:41.985250527Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=6604 len=1129 [2025-04-30T09:12:41.985263982Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1200 bytes [2025-04-30T09:12:41.985268010Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.985270514Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.985285743Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=13 [2025-04-30T09:12:41.985288839Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=5 blocks=[4..7] ecn_counts=None [2025-04-30T09:12:41.985296403Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 684807629 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 9, last_sent_packet: 13, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.985339785Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.985348030Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.986209504Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.986217689Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1151 pn=8 [2025-04-30T09:12:41.986224933Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=7733 len=1129 [2025-04-30T09:12:41.986237066Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1200 bytes [2025-04-30T09:12:41.986240743Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.986243188Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.986252024Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=5 pn=14 [2025-04-30T09:12:41.986254729Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=4 blocks=[4..8] ecn_counts=None [2025-04-30T09:12:41.986261211Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 684807629 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 9, last_sent_packet: 14, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.986304393Z TRACE quiche_apps::client] written 71 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.986312138Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:41.987152231Z TRACE quiche_apps::client] got 1152 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:41.987160116Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Handshake version=1 dcid=3c4caa78f50e75205f3181def2204a9aa1718061 scid=c158c038dbb906457189d8a09cd0ef2f67adbbea len=1103 pn=9 [2025-04-30T09:12:41.987167690Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=8862 len=1081 [2025-04-30T09:12:41.987675757Z TRACE quiche::tls] 3c4caa78f50e75205f3181def2204a9aa1718061 write message lvl=Handshake len=36 [2025-04-30T09:12:41.987681819Z TRACE quiche::tls] 3c4caa78f50e75205f3181def2204a9aa1718061 set write secret lvl=OneRTT [2025-04-30T09:12:41.987690595Z TRACE quiche::tls] 3c4caa78f50e75205f3181def2204a9aa1718061 set read secret lvl=OneRTT [2025-04-30T09:12:41.987703890Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 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(db5eab8166063c535a4eaade69fab7ca), max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1350, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(c158c038dbb906457189d8a09cd0ef2f67adbbea), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-04-30T09:12:41.987725341Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1152 bytes [2025-04-30T09:12:41.987729729Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:41.987732234Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:41.987746521Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm SETTINGS stream=2 len=18 [2025-04-30T09:12:41.987758653Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 open GREASE stream 14 [2025-04-30T09:12:41.987767009Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm GREASE stream=0 len=0 [2025-04-30T09:12:41.987772940Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm GREASE stream=0 len=18 [2025-04-30T09:12:41.987779893Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm HEADERS stream=0 len=29 fin=true [2025-04-30T09:12:41.987786055Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /gwesscingc", "user-agent: quiche"] [2025-04-30T09:12:41.987849204Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Handshake version=1 dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea scid=3c4caa78f50e75205f3181def2204a9aa1718061 len=46 pn=15 [2025-04-30T09:12:41.987853041Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=86 blocks=[4..9] ecn_counts=None [2025-04-30T09:12:41.987856358Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm CRYPTO off=0 len=36 [2025-04-30T09:12:41.987864062Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=100.902518ms latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=112 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.987884611Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10726 out_len=1238 left=1200 [2025-04-30T09:12:41.987892316Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=304 pn=16 [2025-04-30T09:12:41.987895050Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[9f, a4, a8, f9, 14, 76, 7a, df, a4, 24, 39, ac, 78, 1a, 88, 0a, 5b, 84, 57, 88] reset_token=[f4, 40, 84, af, c0, 4f, e5, dd, 1e, ad, 85, 75, 94, 4b, da, b9] [2025-04-30T09:12:41.987902114Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[8d, 4e, f2, 37, 74, 06, 64, 80, d4, a7, 68, d6, 5e, 8d, db, f3, bf, 3c, 0a, 0f] reset_token=[2c, d5, e1, 8c, 9e, 51, db, 57, d4, 06, 57, 17, 1b, 88, 3d, f6] [2025-04-30T09:12:41.987908045Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[e1, b6, 58, ec, 2a, 32, 6f, 05, 5c, 5d, d0, 64, 1b, 8e, 90, aa, 20, c1, 29, 15] reset_token=[74, 70, 98, da, c5, 51, 23, 5b, 33, c9, e2, 8e, a0, 61, b4, fa] [2025-04-30T09:12:41.987913596Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[93, 15, 2e, 6e, 9b, 5f, d5, 2c, 19, 76, 98, 78, bb, 82, 8a, a7, e0, 02, 32, 93] reset_token=[dc, 96, 8b, 22, 73, 3b, e0, 16, 6c, 73, 51, 9a, ba, 5e, 1c, be] [2025-04-30T09:12:41.987919076Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[cf, c3, fc, b1, 68, fa, 8d, fa, 10, 99, 93, 10, 0c, 2e, cc, 06, 62, f8, 4b, 4b] reset_token=[9e, da, 93, 6f, c3, 9e, 41, 9e, 98, 3c, 82, cd, de, 5a, 10, 34] [2025-04-30T09:12:41.987924606Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[cd, 7e, ed, 02, 99, ad, 5b, 76, 73, e2, f0, 8f, fb, f4, 59, 25, ec, b5, c9, a4] reset_token=[55, c2, 6f, 45, 30, c2, c3, af, f0, 73, a1, 42, ad, 7f, 1a, d4] [2025-04-30T09:12:41.987930137Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[83, 40, 68, 33, 38, f1, e1, af, b5, 6f, b2, 28, 3e, 00, c2, d4, 80, 7e, bf, 2c] reset_token=[0f, 25, 83, 11, ad, 75, 6d, 6f, d4, cc, f2, 2d, d4, ab, 3a, da] [2025-04-30T09:12:41.987935767Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm STREAM id=2 off=0 len=19 fin=false [2025-04-30T09:12:41.987955364Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=100.811176ms latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=454 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.988001812Z TRACE quiche_apps::client] written 454 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.988012762Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10042 out_len=1350 left=1312 [2025-04-30T09:12:41.988018754Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=6 pn=17 [2025-04-30T09:12:41.988023032Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm STREAM id=6 off=0 len=1 fin=false [2025-04-30T09:12:41.988033121Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=100.733089ms latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=498 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.988071162Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.988081101Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=9954 out_len=1350 left=1312 [2025-04-30T09:12:41.988086892Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=6 pn=18 [2025-04-30T09:12:41.988090790Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm STREAM id=10 off=0 len=1 fin=false [2025-04-30T09:12:41.988099827Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=100.666293ms latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=542 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.988140774Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.988150542Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=9866 out_len=1350 left=1312 [2025-04-30T09:12:41.988156654Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=72 pn=19 [2025-04-30T09:12:41.988160681Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm STREAM id=0 off=0 len=67 fin=true [2025-04-30T09:12:41.988169738Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=100.596662ms latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=652 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 18, last_sent_packet: 19, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.988203963Z TRACE quiche_apps::client] written 110 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.988213270Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=9646 out_len=1350 left=1312 [2025-04-30T09:12:41.988219051Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=31 pn=20 [2025-04-30T09:12:41.988223059Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm STREAM id=14 off=0 len=26 fin=true [2025-04-30T09:12:41.988232176Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=100.534244ms latest_rtt=31.884774ms srtt=37.384759ms min_rtt=31.884774ms rttvar=15.885351ms cwnd=9450 ssthresh=9450 bytes_in_flight=721 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 483, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 19, last_sent_packet: 20, largest_acked: 9, rate_sample: RateSample { delivery_rate: 208, is_app_limited: true, interval: 720.182909ms, delivered: 150, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 369, tv_nsec: 964624720 }), send_elapsed: 688.298135ms, ack_elapsed: 720.182909ms, rtt: 31.884774ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 315971, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:41.988271290Z TRACE quiche_apps::client] written 69 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:41.988277832Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:42.019924255Z TRACE quiche_apps::client] got 748 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:42.019935636Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=727 pn=10 [2025-04-30T09:12:42.019942740Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm ACK delay=4 blocks=[16..20] ecn_counts=None [2025-04-30T09:12:42.019946507Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet newly acked 16 [2025-04-30T09:12:42.019949202Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet newly acked 17 [2025-04-30T09:12:42.019952388Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet newly acked 18 [2025-04-30T09:12:42.019954582Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet newly acked 19 [2025-04-30T09:12:42.019956996Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 packet newly acked 20 [2025-04-30T09:12:42.019962738Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[08, 21, 6a, a1, cb, 20, 58, cb, ae, 3b, d7, fe, d2, 50, ad, b5, 82, 23, 61, 54] reset_token=[64, 1e, db, b3, 40, ab, 74, b6, c4, cb, c7, 8a, 5c, 1f, 3a, 31] [2025-04-30T09:12:42.019969951Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[a0, 9b, 81, a6, 5c, 41, fa, 95, 60, 94, 2b, 7c, 11, 58, a3, 01, 5e, 80, e3, c3] reset_token=[2c, b5, 92, 66, 3f, 17, cf, 48, 66, a4, a0, 55, 6f, 86, 56, 4a] [2025-04-30T09:12:42.019975692Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[64, 6c, 90, 61, 77, c7, 66, 41, 5a, 64, f1, c8, e7, c3, 8e, a0, be, c4, c3, 63] reset_token=[80, 75, 31, 2a, 6f, 71, ea, e1, 2f, 96, 38, c3, f0, 45, e4, a5] [2025-04-30T09:12:42.019981342Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[74, 0d, 4e, ed, 3c, ee, 9e, 1d, be, b2, 2e, 57, 5f, 4d, da, 79, b4, 8e, c4, 2c] reset_token=[c6, d9, e3, 76, da, 38, ab, d5, 6e, e3, b4, 77, 2a, c3, dc, 7a] [2025-04-30T09:12:42.019987153Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[9e, 32, 2c, 02, 97, bb, 91, 90, 0f, b0, 66, 4f, ef, 23, 7e, 88, d3, 22, 19, d6] reset_token=[f5, 14, 60, 77, 12, c0, 49, 62, 04, a3, 94, 93, 9a, fe, 1c, bd] [2025-04-30T09:12:42.019992704Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ee, bc, 1c, 47, 89, 18, 5c, 5f, ab, d0, 41, 2b, 52, ff, 1d, cf, 9e, f1, bf, c7] reset_token=[49, 7b, c1, 10, 26, 90, 90, 2c, 05, 82, cc, 2c, b7, f8, 6e, b9] [2025-04-30T09:12:42.019998525Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[64, e2, b4, cb, fd, e9, 46, ba, d6, b3, 06, 6f, cf, 38, c5, a2, 5d, 05, d0, 52] reset_token=[1d, 99, 12, c8, 9b, 71, 49, 02, 19, 9c, 09, 81, 8a, 29, ce, c8] [2025-04-30T09:12:42.020008914Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm HANDSHAKE_DONE [2025-04-30T09:12:42.020013553Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 dropped epoch 1 state [2025-04-30T09:12:42.020016519Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm CRYPTO off=0 len=396 [2025-04-30T09:12:42.020045554Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm STREAM id=3 off=0 len=19 fin=false [2025-04-30T09:12:42.020073366Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 748 bytes [2025-04-30T09:12:42.020081311Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:42.020086431Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=23 pn=11 [2025-04-30T09:12:42.020093674Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm STREAM id=7 off=0 len=1 fin=false [2025-04-30T09:12:42.020102391Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 44 bytes [2025-04-30T09:12:42.020107580Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:42.020110666Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=23 pn=12 [2025-04-30T09:12:42.020114393Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm STREAM id=11 off=0 len=1 fin=false [2025-04-30T09:12:42.020118752Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 44 bytes [2025-04-30T09:12:42.020122148Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:42.020124522Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:42.020127087Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 stream id 3 is readable [2025-04-30T09:12:42.020132147Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 3 [2025-04-30T09:12:42.020136154Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 open peer's control stream 3 [2025-04-30T09:12:42.020139531Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 3 [2025-04-30T09:12:42.020142556Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 3 [2025-04-30T09:12:42.020145903Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 16 bytes on stream 3 [2025-04-30T09:12:42.020148688Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(3449688375987315543, 1970946993518856577)]), additional_settings=Some([(3449688375987315543, 1970946993518856577)]) stream=3 payload_len=16 [2025-04-30T09:12:42.020155481Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 stream id 7 is readable [2025-04-30T09:12:42.020158997Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 7 [2025-04-30T09:12:42.020162404Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 stream id 11 is readable [2025-04-30T09:12:42.020166141Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 11 [2025-04-30T09:12:42.020174797Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10869 out_len=1350 left=1307 [2025-04-30T09:12:42.020178253Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=5 pn=21 [2025-04-30T09:12:42.020180809Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=7 blocks=[10..12] ecn_counts=None [2025-04-30T09:12:42.020187291Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=36.135621ms srtt=37.224616ms min_rtt=31.884774ms rttvar=12.234297ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 1092, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 20, last_sent_packet: 21, largest_acked: 20, rate_sample: RateSample { delivery_rate: 16853, is_app_limited: true, interval: 36.135621ms, delivered: 609, prior_delivered: 483, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 686265479 }), send_elapsed: 0ns, ack_elapsed: 36.135621ms, rtt: 36.135621ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 317330, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:42.020233417Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:42.020240992Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:42.021176144Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:42.021184059Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=1329 pn=13 [2025-04-30T09:12:42.021191403Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm STREAM id=0 off=0 len=1307 fin=false [2025-04-30T09:12:42.021198426Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1350 bytes [2025-04-30T09:12:42.021202023Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:42.021204407Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:42.021207052Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 stream id 0 is readable [2025-04-30T09:12:42.021210799Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021213956Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 7 bytes on stream 0 [2025-04-30T09:12:42.021217021Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021219686Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm UNKNOWN raw_type=1950354799486730755 stream=0 payload_len=0 [2025-04-30T09:12:42.021235626Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021238912Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 7 bytes on stream 0 [2025-04-30T09:12:42.021241798Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021245044Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 18 bytes on stream 0 [2025-04-30T09:12:42.021247419Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm UNKNOWN raw_type=3770882821137941844 stream=0 payload_len=18 [2025-04-30T09:12:42.021251466Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021254422Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021257347Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 16 bytes on stream 0 [2025-04-30T09:12:42.021259652Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm HEADERS stream=0 payload_len=16 [2025-04-30T09:12:42.021262557Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0 [2025-04-30T09:12:42.021265092Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true [2025-04-30T09:12:42.021271814Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[113, 117, 105, 99, 104, 101] [2025-04-30T09:12:42.021275572Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value=[53, 49, 50, 48] [2025-04-30T09:12:42.021281332Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "quiche"), ("content-length", "5120")] on stream id 0 [2025-04-30T09:12:42.021285661Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 stream id 0 is readable [2025-04-30T09:12:42.021288737Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021291702Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021294698Z TRACE quiche::h3::stream] 3c4caa78f50e75205f3181def2204a9aa1718061 read 1 bytes on stream 0 [2025-04-30T09:12:42.021297022Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm DATA stream=0 wire_payload_len=5120 [2025-04-30T09:12:42.021301721Z DEBUG quiche_apps::common] got 1250 bytes of response data on stream 0 [2025-04-30T09:12:42.021309145Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10869 out_len=1350 left=1307 [2025-04-30T09:12:42.021312381Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=5 pn=22 [2025-04-30T09:12:42.021314866Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=15 blocks=[10..13] ecn_counts=None [2025-04-30T09:12:42.021321268Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=36.135621ms srtt=37.224616ms min_rtt=31.884774ms rttvar=12.234297ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 1092, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 20, last_sent_packet: 22, largest_acked: 20, rate_sample: RateSample { delivery_rate: 16853, is_app_limited: true, interval: 36.135621ms, delivered: 609, prior_delivered: 483, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 686265479 }), send_elapsed: 0ns, ack_elapsed: 36.135621ms, rtt: 36.135621ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 317330, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:42.021359059Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:42.021365992Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:42.022273502Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:42.022281107Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=1329 pn=14 [2025-04-30T09:12:42.022287499Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm STREAM id=0 off=1307 len=1306 fin=false [2025-04-30T09:12:42.022294432Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1350 bytes [2025-04-30T09:12:42.022297999Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:42.022300383Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:42.022303038Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 stream id 0 is readable [2025-04-30T09:12:42.022307477Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0 [2025-04-30T09:12:42.022319379Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10869 out_len=1350 left=1307 [2025-04-30T09:12:42.022322585Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=5 pn=23 [2025-04-30T09:12:42.022324990Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=4 blocks=[10..14] ecn_counts=None [2025-04-30T09:12:42.022330931Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=36.135621ms srtt=37.224616ms min_rtt=31.884774ms rttvar=12.234297ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 1092, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 20, last_sent_packet: 23, largest_acked: 20, rate_sample: RateSample { delivery_rate: 16853, is_app_limited: true, interval: 36.135621ms, delivered: 609, prior_delivered: 483, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 686265479 }), send_elapsed: 0ns, ack_elapsed: 36.135621ms, rtt: 36.135621ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 317330, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:42.022367039Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:42.022374142Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:42.023382052Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:42.023389836Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=1329 pn=15 [2025-04-30T09:12:42.023396068Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm STREAM id=0 off=2613 len=1306 fin=false [2025-04-30T09:12:42.023402661Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1350 bytes [2025-04-30T09:12:42.023406117Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:42.023408441Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:42.023411086Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 stream id 0 is readable [2025-04-30T09:12:42.023415425Z DEBUG quiche_apps::common] got 1306 bytes of response data on stream 0 [2025-04-30T09:12:42.023422227Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10869 out_len=1350 left=1307 [2025-04-30T09:12:42.023425313Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=5 pn=24 [2025-04-30T09:12:42.023427778Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm ACK delay=4 blocks=[10..15] ecn_counts=None [2025-04-30T09:12:42.023433579Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=none latest_rtt=36.135621ms srtt=37.224616ms min_rtt=31.884774ms rttvar=12.234297ms cwnd=9450 ssthresh=9450 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 722008430 }) Rate { delivered: 1092, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 20, last_sent_packet: 24, largest_acked: 20, rate_sample: RateSample { delivery_rate: 16853, is_app_limited: true, interval: 36.135621ms, delivered: 609, prior_delivered: 483, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 686265479 }), send_elapsed: 0ns, ack_elapsed: 36.135621ms, rtt: 36.135621ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 317330, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:42.023474566Z TRACE quiche_apps::client] written 43 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:42.023481529Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:42.024445466Z TRACE quiche_apps::client] got 1302 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:42.024452539Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=1281 pn=16 [2025-04-30T09:12:42.024458310Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm STREAM id=0 off=3919 len=1258 fin=true [2025-04-30T09:12:42.024464832Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 1302 bytes [2025-04-30T09:12:42.024469271Z TRACE quiche_apps::client] got 69 bytes from 193.167.100.100:443 to 0.0.0.0:46977 [2025-04-30T09:12:42.024472186Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx pkt Short dcid=3c4caa78f50e75205f3181def2204a9aa1718061 key_phase=false len=48 pn=17 [2025-04-30T09:12:42.024475993Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 rx frm STREAM id=15 off=0 len=26 fin=true [2025-04-30T09:12:42.024481454Z TRACE quiche_apps::client] 0.0.0.0:46977: processed 69 bytes [2025-04-30T09:12:42.024484840Z TRACE quiche_apps::client] 0.0.0.0:46977: recv() would block [2025-04-30T09:12:42.024487205Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:42.024489729Z TRACE quiche::h3] 3c4caa78f50e75205f3181def2204a9aa1718061 stream id 0 is readable [2025-04-30T09:12:42.024494899Z DEBUG quiche_apps::common] got 1258 bytes of response data on stream 0 [2025-04-30T09:12:42.024497624Z DEBUG quiche_apps::common] 1/1 responses received [2025-04-30T09:12:42.024500199Z INFO quiche_apps::common] 1/1 response(s) received in 915.627189ms, closing... [2025-04-30T09:12:42.024506751Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=10869 out_len=1350 left=1312 [2025-04-30T09:12:42.024509968Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx pkt Short dcid=c158c038dbb906457189d8a09cd0ef2f67adbbea key_phase=false len=11 pn=25 [2025-04-30T09:12:42.024512372Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-04-30T09:12:42.024518884Z TRACE quiche::recovery::congestion::recovery] 3c4caa78f50e75205f3181def2204a9aa1718061 timer=111.146796ms latest_rtt=36.135621ms srtt=37.224616ms min_rtt=31.884774ms rttvar=12.234297ms cwnd=9450 ssthresh=9450 bytes_in_flight=49 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 370, tv_nsec: 758302953 }) Rate { delivered: 1092, delivered_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, first_sent_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, end_of_app_limited: 24, last_sent_packet: 25, largest_acked: 20, rate_sample: RateSample { delivery_rate: 16853, is_app_limited: true, interval: 36.135621ms, delivered: 609, prior_delivered: 483, prior_time: Some(Instant { tv_sec: 370, tv_nsec: 686265479 }), send_elapsed: 0ns, ack_elapsed: 36.135621ms, rtt: 36.135621ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 317330, last_update: Instant { tv_sec: 370, tv_nsec: 684894883 }, next_time: Instant { tv_sec: 370, tv_nsec: 686265479 }, 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=1.9574338205844317 w_max=13500 } [2025-04-30T09:12:42.024578156Z TRACE quiche_apps::client] written 49 bytes from 0.0.0.0:46977 to 193.167.100.100:443 [2025-04-30T09:12:42.024587684Z TRACE quiche_apps::client] 0.0.0.0:46977 -> 193.167.100.100:443: done writing [2025-04-30T09:12:42.283900740Z TRACE quiche_apps::client] timed out [2025-04-30T09:12:42.283928702Z TRACE quiche] 3c4caa78f50e75205f3181def2204a9aa1718061 draining timeout expired [2025-04-30T09:12:42.283978006Z TRACE quiche_apps::client] done reading [2025-04-30T09:12:42.283982784Z INFO quiche_apps::client] connection closed, recv=18 sent=26 lost=6 retrans=0 sent_bytes=2288 recv_bytes=17009 lost_bytes=223 [local_addr=0.0.0.0:46977 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=18 sent=26 lost=6 retrans=0 rtt=37.224616ms min_rtt=Some(31.884774ms) rttvar=12.234297ms cwnd=9450 sent_bytes=2288 recv_bytes=17009 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=16853]