[2025-04-30T11:48:07.261121532Z INFO quiche_server] listening on [::]:443 [2025-04-30T11:48:07.261143122Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-04-30T11:48:07.261149424Z TRACE quiche_server] GSO detected: false [2025-04-30T11:48:08.082121065Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:40293 to [::]:443 [2025-04-30T11:48:08.082156982Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T11:48:08.082165007Z WARN quiche_server] Doing version negotiation [2025-04-30T11:48:08.082266006Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:40293 to [::]:443 [2025-04-30T11:48:08.082272067Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T11:48:08.082275965Z WARN quiche_server] Doing version negotiation [2025-04-30T11:48:08.082308936Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:40293 to [::]:443 [2025-04-30T11:48:08.082316541Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T11:48:08.082321570Z WARN quiche_server] Doing version negotiation [2025-04-30T11:48:08.082348420Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.297277127Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.297304358Z TRACE quiche_server] got packet Initial version=1 dcid=462d7479e9bd19e44acd77e56a2ac4ab9ed9af84 scid=6a1bf04046e18de3 token= [2025-04-30T11:48:11.297313956Z DEBUG quiche_server] New connection: dcid=462d7479e9bd19e44acd77e56a2ac4ab9ed9af84 scid=5cfcfa085acc3ec868742005bd814897337d2774 [2025-04-30T11:48:11.297519050Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx pkt Initial version=1 dcid=462d7479e9bd19e44acd77e56a2ac4ab9ed9af84 scid=6a1bf04046e18de3 token= len=1162 pn=0 [2025-04-30T11:48:11.297533607Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm CRYPTO off=0 len=286 [2025-04-30T11:48:11.297563603Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3") [2025-04-30T11:48:11.297568172Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop") [2025-04-30T11:48:11.297672387Z TRACE quiche::tls] 5cfcfa085acc3ec868742005bd814897337d2774 write message lvl=Initial len=90 [2025-04-30T11:48:11.297704176Z TRACE quiche::tls] 5cfcfa085acc3ec868742005bd814897337d2774 set write secret lvl=Handshake [2025-04-30T11:48:11.297776020Z TRACE quiche::tls] 5cfcfa085acc3ec868742005bd814897337d2774 write message lvl=Handshake len=726 [2025-04-30T11:48:11.297779006Z TRACE quiche::tls] 5cfcfa085acc3ec868742005bd814897337d2774 set write secret lvl=OneRTT [2025-04-30T11:48:11.297786510Z TRACE quiche::tls] 5cfcfa085acc3ec868742005bd814897337d2774 set read secret lvl=Handshake [2025-04-30T11:48:11.297806107Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm PADDING len=855 [2025-04-30T11:48:11.297822788Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 processed 1200 bytes [2025-04-30T11:48:11.297835702Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.297847765Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx pkt Initial version=1 dcid=6a1bf04046e18de3 scid=5cfcfa085acc3ec868742005bd814897337d2774 len=99 pn=0 [2025-04-30T11:48:11.297851201Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm ACK delay=43 blocks=[0..0] ecn_counts=None [2025-04-30T11:48:11.297855660Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm CRYPTO off=0 len=90 [2025-04-30T11:48:11.297864586Z TRACE quiche::recovery::congestion::recovery] 5cfcfa085acc3ec868742005bd814897337d2774 timer=998.972809ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=154 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 87948495 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 87948495 }, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 87948495 }, next_time: Instant { tv_sec: 447, tv_nsec: 87948495 }, 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-30T11:48:11.297916533Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx pkt Handshake version=1 dcid=6a1bf04046e18de3 scid=5cfcfa085acc3ec868742005bd814897337d2774 len=730 pn=1 [2025-04-30T11:48:11.297923026Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm CRYPTO off=0 len=726 [2025-04-30T11:48:11.297929558Z TRACE quiche::recovery::congestion::recovery] 5cfcfa085acc3ec868742005bd814897337d2774 timer=998.908168ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=938 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 87948495 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 87948495 }, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 87948495 }, next_time: Instant { tv_sec: 447, tv_nsec: 87948495 }, 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-30T11:48:11.297974892Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 87948495 } }) [2025-04-30T11:48:11.297980874Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.297985623Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.297991023Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 done writing [2025-04-30T11:48:11.297995261Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.330785840Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.330813571Z TRACE quiche_server] got packet Initial version=1 dcid=5cfcfa085acc3ec868742005bd814897337d2774 scid=6a1bf04046e18de3 token= [2025-04-30T11:48:11.330827157Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx pkt Initial version=1 dcid=5cfcfa085acc3ec868742005bd814897337d2774 scid=6a1bf04046e18de3 token= len=22 pn=1 [2025-04-30T11:48:11.330839570Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm ACK delay=47 blocks=[0..0] ecn_counts=None [2025-04-30T11:48:11.330846513Z TRACE quiche::recovery::congestion::recovery] 5cfcfa085acc3ec868742005bd814897337d2774 packet newly acked 0 [2025-04-30T11:48:11.330871970Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx pkt Handshake version=1 dcid=5cfcfa085acc3ec868742005bd814897337d2774 scid=6a1bf04046e18de3 len=1103 pn=0 [2025-04-30T11:48:11.330878543Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm ACK delay=47 blocks=[1..1] ecn_counts=None [2025-04-30T11:48:11.330881939Z TRACE quiche::recovery::congestion::recovery] 5cfcfa085acc3ec868742005bd814897337d2774 packet newly acked 1 [2025-04-30T11:48:11.330885927Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm CRYPTO off=0 len=52 [2025-04-30T11:48:11.330938124Z TRACE quiche::tls] 5cfcfa085acc3ec868742005bd814897337d2774 set read secret lvl=OneRTT [2025-04-30T11:48:11.330986996Z TRACE quiche::tls] 5cfcfa085acc3ec868742005bd814897337d2774 write message lvl=OneRTT len=476 [2025-04-30T11:48:11.331003737Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 dropped epoch 1 state [2025-04-30T11:48:11.331009478Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 connection established: proto=Ok("hq-interop") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1472, initial_max_data: 4611686018427387903, initial_max_stream_data_bidi_local: 1250000, initial_max_stream_data_bidi_remote: 1250000, initial_max_stream_data_uni: 1250000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 5, initial_source_connection_id: Some(6a1bf04046e18de3), retry_source_connection_id: None, max_datagram_frame_size: Some(65535), unknown_params: None } [2025-04-30T11:48:11.331029385Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm PADDING len=1026 [2025-04-30T11:48:11.331045816Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 dropped epoch 0 state [2025-04-30T11:48:11.331049422Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 processed 1200 bytes [2025-04-30T11:48:11.331058700Z TRACE quiche_server] got 234 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.331062798Z TRACE quiche_server] got packet Short dcid=5cfcfa085acc3ec868742005bd814897337d2774 key_phase=false [2025-04-30T11:48:11.331068488Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx pkt Short dcid=5cfcfa085acc3ec868742005bd814897337d2774 key_phase=false len=213 pn=0 [2025-04-30T11:48:11.331075241Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[78, 72, ae, 9a, 62, d0, a6, a8] reset_token=[79, c1, 23, d8, f7, ae, 82, 74, 1a, 1b, 71, d2, dc, ed, 81, 64] [2025-04-30T11:48:11.331083246Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[5e, 1c, b4, b5, ca, 28, 1f, eb] reset_token=[b6, f3, 1c, 5b, 34, bc, 16, ed, b4, 36, fd, 4e, a0, 97, 0b, b4] [2025-04-30T11:48:11.331089016Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[2d, 06, e2, 6d, 54, 64, 02, 3d] reset_token=[b1, 23, de, 6e, ca, 81, ed, 06, a6, 18, c8, 9d, 12, d4, 4a, 5d] [2025-04-30T11:48:11.331094707Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[8c, 57, 42, 18, 90, 21, 15, d9] reset_token=[90, cf, f6, d0, e6, 62, d8, ea, 04, b7, c4, 4f, 2b, 57, 01, cb] [2025-04-30T11:48:11.331100718Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[86, e0, 24, 6b, 36, 7a, 1c, 4e] reset_token=[91, cc, fd, a8, 83, 23, 26, 47, 26, 4f, 37, f8, 69, 66, f8, 7b] [2025-04-30T11:48:11.331106359Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[01, 3e, 2e, 66, 8e, bb, 56, 32] reset_token=[4a, f6, af, f1, f3, 27, 2a, ba, 69, 5a, 54, e1, dd, 72, 27, fc] [2025-04-30T11:48:11.331112450Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[ff, 8c, d6, 9b, 39, 22, a0, 91] reset_token=[a4, 59, 05, 2c, 39, b9, 50, 1c, 5b, 2a, b6, 4b, 59, 2d, d2, f6] [2025-04-30T11:48:11.331123251Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 processed 234 bytes [2025-04-30T11:48:11.331127879Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.331136756Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319 [2025-04-30T11:48:11.331151463Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx pkt Short dcid=6a1bf04046e18de3 key_phase=false len=646 pn=2 [2025-04-30T11:48:11.331155100Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm ACK delay=8 blocks=[0..0] ecn_counts=None [2025-04-30T11:48:11.331158907Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[31, 3a, 5c, 20, 9a, c8, 24, 12, d2, 0d, 43, 9a, 67, bf, 4c, 60, 01, ff, 75, 0b] reset_token=[b0, 10, f2, d2, 45, 47, d8, 22, d1, bc, 4f, 56, 9d, 58, 1e, c4] [2025-04-30T11:48:11.331165680Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[22, ac, 08, 49, 12, 57, 86, 34, ac, a3, 07, cc, 8e, 06, f9, d7, 5e, 06, 0c, 6e] reset_token=[d2, 46, 42, 2c, 48, 37, ef, 5c, 41, 16, 46, 35, 86, ec, 66, e7] [2025-04-30T11:48:11.331172232Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[64, 11, 1f, 1e, 4b, 5d, f6, bb, 6b, ce, bf, 55, 31, 4d, 63, bd, 1e, 68, 97, f4] reset_token=[47, 6d, ca, c9, e2, d1, 01, 26, 40, c8, 8d, 20, 88, ce, 75, a2] [2025-04-30T11:48:11.331178424Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[de, 7a, a2, 3f, f1, 82, 75, ef, c0, 51, b1, ff, 55, 36, a8, 02, cd, 0b, e0, d4] reset_token=[c1, 39, be, d2, 9d, 31, 72, 33, d1, 99, 1b, 80, e2, fb, b0, 6e] [2025-04-30T11:48:11.331184735Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm HANDSHAKE_DONE [2025-04-30T11:48:11.331187551Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm CRYPTO off=0 len=476 [2025-04-30T11:48:11.331199734Z TRACE quiche::recovery::congestion::recovery] 5cfcfa085acc3ec868742005bd814897337d2774 timer=107.44745ms latest_rtt=33.033202ms srtt=32.991246ms min_rtt=32.985253ms rttvar=12.381456ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=672 app_limited=true congestion_recovery_start_time=None Rate { delivered: 938, delivered_time: Instant { tv_sec: 447, tv_nsec: 121241133 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 121241133 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28436, is_app_limited: true, interval: 32.985253ms, delivered: 938, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 87948495 }), send_elapsed: 0ns, ack_elapsed: 32.985253ms, rtt: 32.985253ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 511499, last_update: Instant { tv_sec: 447, tv_nsec: 121241133 }, next_time: Instant { tv_sec: 447, tv_nsec: 121241133 }, 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-30T11:48:11.331272851Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 written 672 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 121241133 } }) [2025-04-30T11:48:11.331279693Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.331287458Z TRACE quiche_server] got 299 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.331291135Z TRACE quiche_server] got packet Short dcid=5cfcfa085acc3ec868742005bd814897337d2774 key_phase=false [2025-04-30T11:48:11.331296635Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx pkt Short dcid=5cfcfa085acc3ec868742005bd814897337d2774 key_phase=false len=278 pn=1 [2025-04-30T11:48:11.331302736Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm STREAM id=0 off=0 len=257 fin=true [2025-04-30T11:48:11.331311122Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 processed 299 bytes [2025-04-30T11:48:11.331314659Z DEBUG quiche_apps::common] 5cfcfa085acc3ec868742005bd814897337d2774 response stream 0 is writable with capacity Ok(12828) [2025-04-30T11:48:11.331326811Z TRACE quiche_apps::common] 5cfcfa085acc3ec868742005bd814897337d2774 received 257 bytes [2025-04-30T11:48:11.331330238Z TRACE quiche_apps::common] 5cfcfa085acc3ec868742005bd814897337d2774 stream 0 has 257 bytes (fin? true) [2025-04-30T11:48:11.331336069Z INFO quiche_apps::common] 5cfcfa085acc3ec868742005bd814897337d2774 got GET request for "/www/ljkgficdcwylrrqcbmqizgustwbppjgboptlhuyvkqwtbzifujnnxemxogbmfilzdgdtdoojgctfjnvjyfbdluhidnnpkhpxrapwmefjpwipfrfekcnqjaymrzmgzrvtwchzbdemewurubojkavoykptcckxlnxlemhoxrjigpfhsgcbsvnboaaylbqdzujnwoasestzbunaknbwnysxydswwskisdidvnqlmmixuibqiaonisuwtcxxxf" on stream 0 [2025-04-30T11:48:11.331374220Z INFO quiche_apps::common] 5cfcfa085acc3ec868742005bd814897337d2774 sending response of size 32 on stream 0 [2025-04-30T11:48:11.331381814Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.331387846Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12828 out_len=1350 left=1319 [2025-04-30T11:48:11.331392885Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx pkt Short dcid=6a1bf04046e18de3 key_phase=false len=42 pn=3 [2025-04-30T11:48:11.331396091Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm ACK delay=11 blocks=[0..1] ecn_counts=None [2025-04-30T11:48:11.331399888Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 tx frm STREAM id=0 off=0 len=32 fin=true [2025-04-30T11:48:11.331407442Z TRACE quiche::recovery::congestion::recovery] 5cfcfa085acc3ec868742005bd814897337d2774 timer=107.493777ms latest_rtt=33.033202ms srtt=32.991246ms min_rtt=32.985253ms rttvar=12.381456ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=740 app_limited=true congestion_recovery_start_time=None Rate { delivered: 938, delivered_time: Instant { tv_sec: 447, tv_nsec: 121241133 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 121241133 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28436, is_app_limited: true, interval: 32.985253ms, delivered: 938, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 87948495 }), send_elapsed: 0ns, ack_elapsed: 32.985253ms, rtt: 32.985253ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 511499, last_update: Instant { tv_sec: 447, tv_nsec: 121241133 }, next_time: Instant { tv_sec: 447, tv_nsec: 121241133 }, 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-30T11:48:11.331447647Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 written 68 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 121241133 } }) [2025-04-30T11:48:11.331453618Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.331458097Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.331461443Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 done writing [2025-04-30T11:48:11.331464158Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.362518389Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.362546371Z TRACE quiche_server] got packet Short dcid=5cfcfa085acc3ec868742005bd814897337d2774 key_phase=false [2025-04-30T11:48:11.362560638Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx pkt Short dcid=5cfcfa085acc3ec868742005bd814897337d2774 key_phase=false len=22 pn=2 [2025-04-30T11:48:11.362573763Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm ACK delay=11 blocks=[2..3] ecn_counts=None [2025-04-30T11:48:11.362581006Z TRACE quiche::recovery::congestion::recovery] 5cfcfa085acc3ec868742005bd814897337d2774 packet newly acked 2 [2025-04-30T11:48:11.362595483Z TRACE quiche::recovery::congestion::recovery] 5cfcfa085acc3ec868742005bd814897337d2774 packet newly acked 3 [2025-04-30T11:48:11.362656567Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 processed 43 bytes [2025-04-30T11:48:11.362665935Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.362669903Z TRACE quiche_server] got packet Short dcid=5cfcfa085acc3ec868742005bd814897337d2774 key_phase=false [2025-04-30T11:48:11.362674972Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx pkt Short dcid=5cfcfa085acc3ec868742005bd814897337d2774 key_phase=false len=25 pn=3 [2025-04-30T11:48:11.362680031Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm ACK delay=34 blocks=[2..3] ecn_counts=None [2025-04-30T11:48:11.362684600Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 rx frm APPLICATION_CLOSE err=0 reason=[] [2025-04-30T11:48:11.362692655Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 processed 46 bytes [2025-04-30T11:48:11.362697614Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.362700740Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 done writing [2025-04-30T11:48:11.362703826Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.577981090Z TRACE quiche_server] timed out [2025-04-30T11:48:11.578013220Z TRACE quiche] 5cfcfa085acc3ec868742005bd814897337d2774 draining timeout expired [2025-04-30T11:48:11.578058254Z TRACE quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 done writing [2025-04-30T11:48:11.578062402Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.578066550Z INFO quiche_server] 5cfcfa085acc3ec868742005bd814897337d2774 connection collected recv=7 sent=4 lost=0 retrans=0 sent_bytes=1678 recv_bytes=3022 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:58245 validation_state=Validated active=true recv=7 sent=4 lost=0 retrans=0 rtt=32.795669ms min_rtt=Some(31.426637ms) rttvar=9.677244ms cwnd=13500 sent_bytes=1678 recv_bytes=3022 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=23546] [2025-04-30T11:48:11.708640853Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.708666110Z TRACE quiche_server] got packet Initial version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f token= [2025-04-30T11:48:11.708675598Z DEBUG quiche_server] New connection: dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 [2025-04-30T11:48:11.708834024Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Initial version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f token= len=1162 pn=0 [2025-04-30T11:48:11.708849824Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm CRYPTO off=0 len=557 [2025-04-30T11:48:11.708879098Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3") [2025-04-30T11:48:11.708883637Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop") [2025-04-30T11:48:11.709076087Z TRACE quiche::tls] e7df5084105002b7a70e6dfe9756ab82b31d58a6 write message lvl=Initial len=96 [2025-04-30T11:48:11.709096655Z TRACE quiche::tls] e7df5084105002b7a70e6dfe9756ab82b31d58a6 set write secret lvl=Handshake [2025-04-30T11:48:11.709132613Z TRACE quiche::tls] e7df5084105002b7a70e6dfe9756ab82b31d58a6 write message lvl=Handshake len=180 [2025-04-30T11:48:11.709136089Z TRACE quiche::tls] e7df5084105002b7a70e6dfe9756ab82b31d58a6 set write secret lvl=OneRTT [2025-04-30T11:48:11.709170123Z TRACE quiche::tls] e7df5084105002b7a70e6dfe9756ab82b31d58a6 write message lvl=OneRTT len=476 [2025-04-30T11:48:11.709174020Z TRACE quiche::tls] e7df5084105002b7a70e6dfe9756ab82b31d58a6 set read secret lvl=ZeroRTT [2025-04-30T11:48:11.709182796Z TRACE quiche::tls] e7df5084105002b7a70e6dfe9756ab82b31d58a6 set read secret lvl=Handshake [2025-04-30T11:48:11.709199728Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm PADDING len=584 [2025-04-30T11:48:11.709227460Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 1200 bytes [2025-04-30T11:48:11.709246275Z TRACE quiche_server] got 315 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.709250944Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.709257817Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=278 pn=0 [2025-04-30T11:48:11.709264660Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=0 off=0 len=257 fin=true [2025-04-30T11:48:11.709275360Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 315 bytes [2025-04-30T11:48:11.709278816Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 0 is writable with capacity Ok(13500) [2025-04-30T11:48:11.709286330Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.709289626Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 0 has 257 bytes (fin? true) [2025-04-30T11:48:11.709294746Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/ljkgficdcwylrrqcbmqizgustwbppjgboptlhuyvkqwtbzifujnnxemxogbmfilzdgdtdoojgctfjnvjyfbdluhidnnpkhpxrapwmefjpwipfrfekcnqjaymrzmgzrvtwchzbdemewurubojkavoykptcckxlnxlemhoxrjigpfhsgcbsvnboaaylbqdzujnwoasestzbunaknbwnysxydswwskisdidvnqlmmixuibqiaonisuwtcxxxf" on stream 0 [2025-04-30T11:48:11.709320634Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 0 [2025-04-30T11:48:11.709329000Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.709343206Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Initial version=1 dcid=6e61099753ec072f scid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 len=106 pn=0 [2025-04-30T11:48:11.709347264Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=67 blocks=[0..0] ecn_counts=None [2025-04-30T11:48:11.709352344Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm CRYPTO off=0 len=96 [2025-04-30T11:48:11.709363324Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=998.96771ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=161 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 499442374 }, next_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, 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-30T11:48:11.709386437Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Handshake version=1 dcid=6e61099753ec072f scid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 len=184 pn=1 [2025-04-30T11:48:11.709389814Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm CRYPTO off=0 len=180 [2025-04-30T11:48:11.709396336Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=998.935219ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=399 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 499442374 }, next_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, 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-30T11:48:11.709414640Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13101 out_len=801 left=770 [2025-04-30T11:48:11.709423677Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=775 pn=2 [2025-04-30T11:48:11.709426843Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=19 blocks=[0..0] ecn_counts=None [2025-04-30T11:48:11.709430279Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[72, 94, da, df, fb, b3, 58, f1, d1, ee, 21, e9, d1, ec, 72, 09, bf, 3c, 81, cb] reset_token=[77, 99, 29, 0a, 7d, 5c, 4b, 13, 73, 7e, a6, 7c, 69, 3a, 3a, 3b] [2025-04-30T11:48:11.709438465Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[04, 90, f5, 06, 45, ed, cf, 23, 83, 4c, 5e, 5f, f4, 9b, 14, 17, 58, d6, 27, b1] reset_token=[dd, e1, 74, 62, 3e, 9c, 83, 34, 9b, e0, ba, c4, b2, ee, af, 1a] [2025-04-30T11:48:11.709445378Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[97, c9, 8c, 89, 63, ab, 93, 08, 64, bd, 92, f0, 05, 9e, ec, 7d, dc, 91, 65, 0a] reset_token=[c7, c2, 8c, 9b, 65, 67, 0b, 04, be, 6a, 1b, ad, 90, 9d, b8, 50] [2025-04-30T11:48:11.709452300Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[e3, 55, ab, 48, 9a, e0, 69, cc, 24, f3, 32, 43, 28, 64, 3e, 4f, 21, 55, 0d, 47] reset_token=[23, 73, 3f, 8e, 39, a6, 9b, 20, 16, 1b, 8a, 00, 48, 56, 83, 96] [2025-04-30T11:48:11.709458833Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm CRYPTO off=0 len=476 [2025-04-30T11:48:11.709461678Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=0 off=0 len=32 fin=true [2025-04-30T11:48:11.709464734Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm PADDING len=93 [2025-04-30T11:48:11.709474923Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=998.856743ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1200 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 1, last_sent_packet: 2, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 499442374 }, next_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, 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-30T11:48:11.709488087Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.709522822Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 499442374 } }) [2025-04-30T11:48:11.709528754Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.709762481Z TRACE quiche_server] got 1098 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.709770195Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.709777048Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=1061 pn=1 [2025-04-30T11:48:11.709783239Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=4 off=0 len=257 fin=true [2025-04-30T11:48:11.709787017Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=8 off=0 len=257 fin=true [2025-04-30T11:48:11.709789932Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=12 off=0 len=257 fin=true [2025-04-30T11:48:11.709793549Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=16 off=0 len=257 fin=true [2025-04-30T11:48:11.709803928Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 1098 bytes [2025-04-30T11:48:11.709806714Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 4 is writable with capacity Ok(12300) [2025-04-30T11:48:11.709809358Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 8 is writable with capacity Ok(12300) [2025-04-30T11:48:11.709811592Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 12 is writable with capacity Ok(12300) [2025-04-30T11:48:11.709813857Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 16 is writable with capacity Ok(12300) [2025-04-30T11:48:11.709818225Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.709820569Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 4 has 257 bytes (fin? true) [2025-04-30T11:48:11.709824286Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/rcsddseypzokydeekqkvdzvvdcztgzampwleszjomtcshwcsjjjkihebpjnbaitawyjnvyvcpdjkhdtnuwnlzudtdpjjhwdznscctqkqyenfjdwgffwiaohvqvntjemlpirjqommxsbgfvzbwrzgxjrqtckgfyycowkocrlkfopjsutfbmrtrqogtzytfsjdpzrjyvsuvfbmjcxzdyuqhwrmoygrckfjzsbvdsbqmuojdftjytuidfbbyx" on stream 4 [2025-04-30T11:48:11.709844264Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 4 [2025-04-30T11:48:11.709849784Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.709852078Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 8 has 257 bytes (fin? true) [2025-04-30T11:48:11.709855034Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/ykbqgbgmaaavhmbagqdiiviqpvffnrieyavucnwiidtqkvsnrptwumnfflezhkkjaqchzshuiqydjqocskbsldrkoysiwbvgxzflfpuzfvtubyrckambbdssmkhzzyhxzlikxpxregvwfmjeyamrbewueioivdgbhwuzarlhkdiaiulropvasoghgwoihjzkzucwgmlipvcwgjayhvilrmqfvngczvdpwcxsookccytpluzzkkamhhcoyk" on stream 8 [2025-04-30T11:48:11.709869861Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 8 [2025-04-30T11:48:11.709874440Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.709876745Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 12 has 257 bytes (fin? true) [2025-04-30T11:48:11.709879510Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/uylhuloxtojsvrderibpuywlnyxayfdprissdvbftrdhhakfvrpnixgezzkrjubvqjkvgiclwhgottpaznuoqvgdcuzegpyhrzibpogferaggiybirhpuqrqwyzammsnnxsoefynymhiufdqdtnxipmicpgiixfempwzfmrusahvfiackfxinepxusulodriluoggejwvboktnyypzhwwwuxeerxqhybnlxkxcywcndujtbmmmlsbxjkyg" on stream 12 [2025-04-30T11:48:11.709915697Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 12 [2025-04-30T11:48:11.709924674Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.709927079Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 16 has 257 bytes (fin? true) [2025-04-30T11:48:11.709930094Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/tfkgwgfqgoxfnnqapbdsijwrkegikzzidvapmzbqzfbnpsnffiwbbxgfwccxsxlopvqoddotihyzrxwgysrqvqwkqggavnbmybrlzygbmkrfhxofznmuhikdxmrjngktixltwdxiqsvbsczaavtcjmjipayksjhbeoounhkmhqjuhblyfmytrztiqhvrvilcvdgmdlokjwhqmelpnuhswtbgisojonpiaagjqdialixkggjhfgehhixarb" on stream 16 [2025-04-30T11:48:11.709944391Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 16 [2025-04-30T11:48:11.709950272Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.709957415Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12300 out_len=1200 left=1169 [2025-04-30T11:48:11.709961944Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=42 pn=3 [2025-04-30T11:48:11.709964599Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=22 blocks=[0..1] ecn_counts=None [2025-04-30T11:48:11.709967845Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=4 off=0 len=32 fin=true [2025-04-30T11:48:11.709974778Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=998.356767ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1268 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 2, last_sent_packet: 3, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 500063145 }, next_time: Instant { tv_sec: 447, tv_nsec: 500063145 }, 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-30T11:48:11.710012058Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 68 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 500063145 } }) [2025-04-30T11:48:11.710017348Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.710023248Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.710030973Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12232 out_len=1200 left=1174 [2025-04-30T11:48:11.710037215Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=4 [2025-04-30T11:48:11.710041593Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=8 off=0 len=32 fin=true [2025-04-30T11:48:11.710051141Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=998.280134ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1331 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 3, last_sent_packet: 4, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 500137233 }, next_time: Instant { tv_sec: 447, tv_nsec: 500137233 }, 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-30T11:48:11.710098259Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 500137233 } }) [2025-04-30T11:48:11.710104961Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.710110702Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.710118587Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12169 out_len=1200 left=1174 [2025-04-30T11:48:11.710124919Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=5 [2025-04-30T11:48:11.710129457Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=12 off=0 len=32 fin=true [2025-04-30T11:48:11.710139065Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=998.192219ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1394 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 4, last_sent_packet: 5, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 500224226 }, next_time: Instant { tv_sec: 447, tv_nsec: 500224226 }, 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-30T11:48:11.710176455Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 500224226 } }) [2025-04-30T11:48:11.710181244Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.710186754Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.710194769Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12106 out_len=1200 left=1174 [2025-04-30T11:48:11.710201011Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=6 [2025-04-30T11:48:11.710205319Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=16 off=0 len=32 fin=true [2025-04-30T11:48:11.710215949Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=998.115406ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1457 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 5, last_sent_packet: 6, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 500300689 }, next_time: Instant { tv_sec: 447, tv_nsec: 500300689 }, 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-30T11:48:11.710258649Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 500300689 } }) [2025-04-30T11:48:11.710263708Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.710269930Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.710275510Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.710279087Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.710853942Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.710863070Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.710869351Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=1313 pn=2 [2025-04-30T11:48:11.710875242Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=20 off=0 len=257 fin=true [2025-04-30T11:48:11.710879430Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=24 off=0 len=257 fin=true [2025-04-30T11:48:11.710882536Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=28 off=0 len=257 fin=true [2025-04-30T11:48:11.710886674Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=32 off=0 len=257 fin=true [2025-04-30T11:48:11.710889780Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=36 off=0 len=250 fin=false [2025-04-30T11:48:11.710916990Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 1350 bytes [2025-04-30T11:48:11.710920016Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 20 is writable with capacity Ok(12043) [2025-04-30T11:48:11.710922761Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 24 is writable with capacity Ok(12043) [2025-04-30T11:48:11.710924995Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 28 is writable with capacity Ok(12043) [2025-04-30T11:48:11.710927219Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 32 is writable with capacity Ok(12043) [2025-04-30T11:48:11.710929434Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 36 is writable with capacity Ok(12043) [2025-04-30T11:48:11.710933712Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.710936156Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 20 has 257 bytes (fin? true) [2025-04-30T11:48:11.710939713Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/trwxjysicixfedqwhskyrovkppcwkoskfzaqzuvgdqpewanymjqgdjzwappmrjtbliubkdgcuhlfnbelylxzjdzrocbgalqhyvrobxrbduzieooejosqvxsmyddqotzrgkajmyjugbeomohunpeflgrovymjxmxhdospnwdfqgtvpiczehqaejfcfjtwxjvahwxfvtjkkmqylmoqaulascohlcxhdkjalgpbxpjyyqvhweomgdjttuggga" on stream 20 [2025-04-30T11:48:11.710964259Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 20 [2025-04-30T11:48:11.710987392Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.710990037Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 24 has 257 bytes (fin? true) [2025-04-30T11:48:11.710993063Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/hukbmbefeasqgwqzekqgqywdepofrjgkaguatlptpzleqecfxbaubtzutvabohbpplrwgjutmelvxlwzngdukutemrdsdpnvkqkfzsliwapffpsupfjinvvbnuaiouossdfiumlzmlsllsbplzvnqunwizlbuuhrhbnuzbfqocxfvaaqwzlgdmkrrzishymovjkwuowyhneubuqgbatdoauabmivqbbdoxdzyaprxlzgygibqqaoyslbxu" on stream 24 [2025-04-30T11:48:11.711007810Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 24 [2025-04-30T11:48:11.711012399Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.711014733Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 28 has 257 bytes (fin? true) [2025-04-30T11:48:11.711017568Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/dpuqjydrybxmutrgekicirihsgdhvtvehyopezepmfpmmqifznexueisvwuvpmxwqhqihvcmntkxkikekvjemkrdxmbxthsmtsoxfqtxfgbzxdyvqqrjopsjrfjxdfgafmxrcnjhgtrpzxfnhtulrwtocjuxorwpmfedxivulobhogiwfzkwiqyvcfhcrlcoeyjmsenjarxwypzzzrtmmiyjeusxupzkpciapmnwmlfxlfrnekbuabgqge" on stream 28 [2025-04-30T11:48:11.711030803Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 28 [2025-04-30T11:48:11.711035161Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.711037466Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 32 has 257 bytes (fin? true) [2025-04-30T11:48:11.711040251Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/fxutifhuczodxapjagayynmyrfjawwkvxgbzuuecevweqfulzlwhyjtobnwubjbktdvrvvpsnmbeszwpwrjmhgzcgkyymcpexlwvyxzfdhatudpfgqocmuaxnigpwhhvrtmgtauufekrpffwkjcwzvbgfzipvhgxbvqwdhtbvorgvihaydxwfqxmrwkgmovcsnvtemiuwrqyxuprmkvyowzakjrmsrqvdgqkmltyefimlsfhhcmalmgkfa" on stream 32 [2025-04-30T11:48:11.711053606Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 32 [2025-04-30T11:48:11.711058235Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 250 bytes [2025-04-30T11:48:11.711060549Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 36 has 250 bytes (fin? false) [2025-04-30T11:48:11.711065007Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.711071630Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12043 out_len=1200 left=1169 [2025-04-30T11:48:11.711075938Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=42 pn=7 [2025-04-30T11:48:11.711078493Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=25 blocks=[0..2] ecn_counts=None [2025-04-30T11:48:11.711081679Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=20 off=0 len=32 fin=true [2025-04-30T11:48:11.711088681Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=997.242864ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1525 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 6, last_sent_packet: 7, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 501177650 }, next_time: Instant { tv_sec: 447, tv_nsec: 501177650 }, 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-30T11:48:11.711128626Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 68 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 501177650 } }) [2025-04-30T11:48:11.711134718Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.711140999Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.711148573Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11975 out_len=1200 left=1174 [2025-04-30T11:48:11.711154635Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=8 [2025-04-30T11:48:11.711158863Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=24 off=0 len=32 fin=true [2025-04-30T11:48:11.711168911Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=997.162393ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1588 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 7, last_sent_packet: 8, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 501254694 }, next_time: Instant { tv_sec: 447, tv_nsec: 501254694 }, 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-30T11:48:11.711210990Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 501254694 } }) [2025-04-30T11:48:11.711216360Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.711222492Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.711231148Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11912 out_len=1200 left=1174 [2025-04-30T11:48:11.711237270Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=9 [2025-04-30T11:48:11.711241247Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=28 off=0 len=32 fin=true [2025-04-30T11:48:11.711250715Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=997.080249ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1651 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 8, last_sent_packet: 9, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 501336357 }, next_time: Instant { tv_sec: 447, tv_nsec: 501336357 }, 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-30T11:48:11.711296180Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 501336357 } }) [2025-04-30T11:48:11.711302020Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.711308072Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.711316578Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11849 out_len=1200 left=1174 [2025-04-30T11:48:11.711322980Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=10 [2025-04-30T11:48:11.711327208Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=32 off=0 len=32 fin=true [2025-04-30T11:48:11.711338339Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=996.992936ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1714 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 9, last_sent_packet: 10, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 501421857 }, next_time: Instant { tv_sec: 447, tv_nsec: 501421857 }, 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-30T11:48:11.711378023Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 501421857 } }) [2025-04-30T11:48:11.711382902Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.711389234Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.711394724Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.711398601Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.711968086Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.711978596Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.711984648Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=1313 pn=3 [2025-04-30T11:48:11.711990599Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=40 off=0 len=257 fin=true [2025-04-30T11:48:11.711994807Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=44 off=0 len=257 fin=true [2025-04-30T11:48:11.712002972Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=48 off=0 len=257 fin=true [2025-04-30T11:48:11.712006128Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=52 off=0 len=257 fin=true [2025-04-30T11:48:11.712009374Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=56 off=0 len=250 fin=false [2025-04-30T11:48:11.712020705Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 1350 bytes [2025-04-30T11:48:11.712023500Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 36 is writable with capacity Ok(11786) [2025-04-30T11:48:11.712026105Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 40 is writable with capacity Ok(11786) [2025-04-30T11:48:11.712028339Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 44 is writable with capacity Ok(11786) [2025-04-30T11:48:11.712030543Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 48 is writable with capacity Ok(11786) [2025-04-30T11:48:11.712032758Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 52 is writable with capacity Ok(11786) [2025-04-30T11:48:11.712034962Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 56 is writable with capacity Ok(11786) [2025-04-30T11:48:11.712039190Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.712041544Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 40 has 257 bytes (fin? true) [2025-04-30T11:48:11.712045090Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/uhdimsvxznjoplxiwxzwvkuojzwehgkgnhmcpuapmmqvnchgcntmfnsbjgxwakzoisfmwdhdpczmvyofjjqubtawipakypkwijhyiaczvugzhsxyjphdoexdmnebceydsgorvyhyeuurykcpkdlpxpfydftquskhfiegqornbevnqqgvlkkhixepopqgzcvlvwqilidinbgzxrkshdnfosovkdcrsmewgibstjszezajtowzgkpapyiqlh" on stream 40 [2025-04-30T11:48:11.712064326Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 40 [2025-04-30T11:48:11.712069727Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.712072061Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 44 has 257 bytes (fin? true) [2025-04-30T11:48:11.712074836Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/ghcoeosnzaqksmzjsyljdjblcqctdtamoljecgwrbxnhncfhkavcutpotynnzkmkbaelwalfdpvpfwmggxyqilhpvzlslbpubnsjbjpqbviikxldhslbrbvqoelgliumrpsejyjuteiduvznvhxtmibyliynhlvmhmxrcxpsznwkhzrspxvihtwojxrurvamsvgtbbfnaxdyrdulotnudbvaztxtvaygtrneyfygxbsugkdzzgvmdnzhgg" on stream 44 [2025-04-30T11:48:11.712088582Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 44 [2025-04-30T11:48:11.712092900Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.712095184Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 48 has 257 bytes (fin? true) [2025-04-30T11:48:11.712097809Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/yyzsqrxgakigeiwcxsxvhgwfiufutixdmceyympswzknamwsgjseqgblpcmmgybvjszeaqfjbmiaywkahfqqfjaqrhlbgqtsegzoabskfeqooljygwemgxvmapjdhdezkisjllduozrknajnvtvimdqclvkjxxnvqyzdmkhaalsojmkugagdqivacsytmflgqhovmwbpubvolxlnjpepfqikcuusipnhtrygmfrqowscdicmmsmofufnpu" on stream 48 [2025-04-30T11:48:11.712110924Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 48 [2025-04-30T11:48:11.712115242Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.712117566Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 52 has 257 bytes (fin? true) [2025-04-30T11:48:11.712123998Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/fackssewoidodkrnnmwxcmvadfyxutjoqdcwcgtgzsmtkeduwhoofemofsqkrcxvijlzrxnosjknytdxoxrpterjitkaisavnxfbvvlppsfvjhjyzoktrixhyaukxtbtszkcgxxaxraelzmfhzjtzerjtfmumnsslxpbhpfvcnnrxrtthiqylspesccwiqtsenufzbzfqmeclkagelyhfxlopmjbqxlutrxrlnjrkuefmqhnidtjoxmwtu" on stream 52 [2025-04-30T11:48:11.712137353Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 52 [2025-04-30T11:48:11.712141912Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 250 bytes [2025-04-30T11:48:11.712144236Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 56 has 250 bytes (fin? false) [2025-04-30T11:48:11.712148274Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.712154746Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11786 out_len=1200 left=1169 [2025-04-30T11:48:11.712158994Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=42 pn=11 [2025-04-30T11:48:11.712161568Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=21 blocks=[0..3] ecn_counts=None [2025-04-30T11:48:11.712164734Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=40 off=0 len=32 fin=true [2025-04-30T11:48:11.712171427Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=996.160168ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1782 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 10, last_sent_packet: 11, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 502260896 }, next_time: Instant { tv_sec: 447, tv_nsec: 502260896 }, 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-30T11:48:11.712206953Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 68 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 502260896 } }) [2025-04-30T11:48:11.712213285Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.712219046Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.712227021Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11718 out_len=1200 left=1174 [2025-04-30T11:48:11.712233142Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=12 [2025-04-30T11:48:11.712237080Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=44 off=0 len=32 fin=true [2025-04-30T11:48:11.712246217Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=996.085007ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1845 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 11, last_sent_packet: 12, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 502333482 }, next_time: Instant { tv_sec: 447, tv_nsec: 502333482 }, 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-30T11:48:11.712297052Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 502333482 } }) [2025-04-30T11:48:11.712305818Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.712314875Z TRACE quiche_server] got 339 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.712320305Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.712328851Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=302 pn=4 [2025-04-30T11:48:11.712334402Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=60 off=0 len=257 fin=true [2025-04-30T11:48:11.712338389Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=36 off=250 len=7 fin=true [2025-04-30T11:48:11.712341455Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=56 off=250 len=7 fin=true [2025-04-30T11:48:11.712351263Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 339 bytes [2025-04-30T11:48:11.712354008Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 36 is writable with capacity Ok(11655) [2025-04-30T11:48:11.712356653Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 56 is writable with capacity Ok(11655) [2025-04-30T11:48:11.712358877Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 60 is writable with capacity Ok(11655) [2025-04-30T11:48:11.712362995Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.712365269Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 60 has 257 bytes (fin? true) [2025-04-30T11:48:11.712368876Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/iiwghdlldqdhttmyvcxclianbfttoxieisafhdeqjmdejjtktuoluytkwotcumnowcyhnqweouzqlwkghbbdjqpxtbohjmkhnrxkuavyhjeswdskitkicgsarpvulmcrnxpisswgiriyjtmqdyyqkqydtimkcfxjbbgzfilryygpsznmnrauemyvplonqnbgjwpqwykqdrgbziaatcrljgtjausgdbtypotarpqmblszaxgbizsikgvnmc" on stream 60 [2025-04-30T11:48:11.712388232Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 60 [2025-04-30T11:48:11.712393893Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 7 bytes [2025-04-30T11:48:11.712396307Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 36 has 7 bytes (fin? true) [2025-04-30T11:48:11.712399804Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/iajcndcysuaapfymwtxlpczopkxjsisxbaiwsukpoivtrvvdlcmrsnjznhheemvfhkxeozljqopahzzazqyljkvmmpmszavvwogzdlfnevhxcufzdzovohzanysetemsezyyapmqfgamqlayeacaywwkwzukjwyjhbopqzpelvolbiupgwmwdvxenyxlpnvbsclevkgbmaukxzymhrzrbuagwwticjvweecukvibbshhkvrsvvtrnzgwjv" on stream 36 [2025-04-30T11:48:11.712413880Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 36 [2025-04-30T11:48:11.712418349Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 7 bytes [2025-04-30T11:48:11.712428287Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 56 has 7 bytes (fin? true) [2025-04-30T11:48:11.712434018Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/ywvyssvvxoflexvydxpwrqxsumoigqpkoyjybdjkgvbnyqkuplhpkqcjmjzkjekudmuufbanxqpwntkuwnurhnxqgrfliddxwbveffavoiotghiorqomvcpaxagqmsyxqokzvciuixhtlybhbisabwywmqigoicgjxzykdbvjfukadpcdhqufusfmzyuydbonwysqmsectqseabxzahnrcyryhxdroikokhxjrmzgnvzhlelwgkjkqltqk" on stream 56 [2025-04-30T11:48:11.712458584Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 56 [2025-04-30T11:48:11.712469214Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.712479353Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11655 out_len=1200 left=1169 [2025-04-30T11:48:11.712486115Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=42 pn=13 [2025-04-30T11:48:11.712490263Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=18 blocks=[0..4] ecn_counts=None [2025-04-30T11:48:11.712495443Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=48 off=0 len=32 fin=true [2025-04-30T11:48:11.712503508Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=995.828027ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1913 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 12, last_sent_packet: 13, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 502583720 }, next_time: Instant { tv_sec: 447, tv_nsec: 502583720 }, 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-30T11:48:11.712536580Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 68 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 502583720 } }) [2025-04-30T11:48:11.712542912Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.712552239Z TRACE quiche_server] got 316 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.712557559Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.712566225Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=279 pn=5 [2025-04-30T11:48:11.712574711Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=64 off=0 len=257 fin=true [2025-04-30T11:48:11.712589038Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 316 bytes [2025-04-30T11:48:11.712593847Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 64 is writable with capacity Ok(11587) [2025-04-30T11:48:11.712600550Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.712603385Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 64 has 257 bytes (fin? true) [2025-04-30T11:48:11.712606982Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/yrxytxfolfigphmchpgfdeljnjpkxqzdxhfbdmqhyhvrcrtynuhgludbyxzsbzajgpdnyfcbipvmhycvyrzvgqzwviilkwrnbwgzhhasjohrimlwmtafimlteipjnuxxslroufjbtmrgtvateqahdgktexwsnvtgqehgqjteonjtxxetyjzfgrpidnlanpufmvmzxolvhcbvxmihzjchnfrsbpoikfokzhweumgjpwomwfxqicdrjatzjw" on stream 64 [2025-04-30T11:48:11.712630856Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 64 [2025-04-30T11:48:11.712637358Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.712643911Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11587 out_len=1200 left=1169 [2025-04-30T11:48:11.712647898Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=42 pn=14 [2025-04-30T11:48:11.712650453Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=9 blocks=[0..5] ecn_counts=None [2025-04-30T11:48:11.712653569Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=52 off=0 len=32 fin=true [2025-04-30T11:48:11.712660151Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=995.671474ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1981 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 13, last_sent_packet: 14, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 502750141 }, next_time: Instant { tv_sec: 447, tv_nsec: 502750141 }, 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-30T11:48:11.712703933Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 68 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 502750141 } }) [2025-04-30T11:48:11.712712409Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.712718330Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.712726786Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11519 out_len=1200 left=1174 [2025-04-30T11:48:11.712733097Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=15 [2025-04-30T11:48:11.712737396Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=60 off=0 len=32 fin=true [2025-04-30T11:48:11.712747244Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=995.58405ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2044 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 14, last_sent_packet: 15, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 502832164 }, next_time: Instant { tv_sec: 447, tv_nsec: 502832164 }, 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-30T11:48:11.712796997Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 502832164 } }) [2025-04-30T11:48:11.712805603Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.712814139Z TRACE quiche_server] got 316 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.712819339Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.712828035Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=279 pn=6 [2025-04-30T11:48:11.712836912Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=68 off=0 len=257 fin=true [2025-04-30T11:48:11.712864794Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 316 bytes [2025-04-30T11:48:11.712868060Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 68 is writable with capacity Ok(11456) [2025-04-30T11:48:11.712872789Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.712875113Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 68 has 257 bytes (fin? true) [2025-04-30T11:48:11.712878670Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/mheuvsbjfbhphlqdpooxdpzdsfdybmiecfsmsjpynhbocnijjmxogfytvjqtbkxbjyjhbqoyhebwgdxyjmqcxfkgdxabrzzjkdlbgegxfneipfaypbktjubcxqspkewaxpogkfmxehcovutjxqrkfpeyixumuserzzuygtlqqqtkrjdaldqybduinsthduwvwjmatznbeefhbbbcurobhdbhnwdwpxsycldgnwozsqgfkiogdrgyghpqdg" on stream 68 [2025-04-30T11:48:11.712912103Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 68 [2025-04-30T11:48:11.712921710Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.712931519Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11456 out_len=1200 left=1169 [2025-04-30T11:48:11.712938111Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=42 pn=16 [2025-04-30T11:48:11.712942018Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=12 blocks=[0..6] ecn_counts=None [2025-04-30T11:48:11.712947128Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=36 off=0 len=32 fin=true [2025-04-30T11:48:11.712958018Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=995.373517ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2112 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 15, last_sent_packet: 16, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 503036136 }, next_time: Instant { tv_sec: 447, tv_nsec: 503036136 }, 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-30T11:48:11.712998835Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 68 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 503036136 } }) [2025-04-30T11:48:11.713005447Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.713011639Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.713020064Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11388 out_len=1200 left=1174 [2025-04-30T11:48:11.713026246Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=37 pn=17 [2025-04-30T11:48:11.713030434Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=56 off=0 len=32 fin=true [2025-04-30T11:48:11.713039962Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=995.291243ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2175 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 16, last_sent_packet: 17, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 503125664 }, next_time: Instant { tv_sec: 447, tv_nsec: 503125664 }, 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-30T11:48:11.713079225Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 63 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 503125664 } }) [2025-04-30T11:48:11.713085156Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.713091117Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.713099914Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11325 out_len=1200 left=1174 [2025-04-30T11:48:11.713106235Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=18 [2025-04-30T11:48:11.713110434Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=64 off=0 len=32 fin=true [2025-04-30T11:48:11.713121664Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=995.20953ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2239 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 17, last_sent_packet: 18, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 503204882 }, next_time: Instant { tv_sec: 447, tv_nsec: 503204882 }, 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-30T11:48:11.713169604Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 503204882 } }) [2025-04-30T11:48:11.713176156Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.713182188Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.713190664Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11261 out_len=1200 left=1174 [2025-04-30T11:48:11.713197045Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=19 [2025-04-30T11:48:11.713201353Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=68 off=0 len=32 fin=true [2025-04-30T11:48:11.713210641Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=995.120623ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2303 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 18, last_sent_packet: 19, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 503295962 }, next_time: Instant { tv_sec: 447, tv_nsec: 503295962 }, 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-30T11:48:11.713249023Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 503295962 } }) [2025-04-30T11:48:11.713254623Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.713260835Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.713266646Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.713270333Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.713932431Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.713943432Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.713949523Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=1313 pn=7 [2025-04-30T11:48:11.713955574Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=72 off=0 len=257 fin=true [2025-04-30T11:48:11.713959622Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=76 off=0 len=257 fin=true [2025-04-30T11:48:11.713962568Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=80 off=0 len=257 fin=true [2025-04-30T11:48:11.713965463Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=84 off=0 len=257 fin=true [2025-04-30T11:48:11.713968599Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=88 off=0 len=245 fin=false [2025-04-30T11:48:11.713979148Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 1350 bytes [2025-04-30T11:48:11.713981934Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 72 is writable with capacity Ok(11197) [2025-04-30T11:48:11.713984619Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 76 is writable with capacity Ok(11197) [2025-04-30T11:48:11.713991932Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 80 is writable with capacity Ok(11197) [2025-04-30T11:48:11.713995108Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 84 is writable with capacity Ok(11197) [2025-04-30T11:48:11.713997342Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 88 is writable with capacity Ok(11197) [2025-04-30T11:48:11.714001681Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.714004045Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 72 has 257 bytes (fin? true) [2025-04-30T11:48:11.714007632Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/njpjrssjutvakmwxydztbxreukljgmunggpnwxsqqsxuzfyzryhwnvohcjduijezzptchgpaqbnybnqjgtdrjhvclkmhabaesmuzssmvtvhkobyfxlepyinmdfdyinhdtmupbcrznvhzadforcukxvwhmanqickdzetlowdpfncsmeknyhdxrujwwbrnfjoukuggobyeomohphrunvacfzmkafuzkknfotpdrecmoebtotdhbgdceqdzff" on stream 72 [2025-04-30T11:48:11.714026938Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 72 [2025-04-30T11:48:11.714032188Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.714034532Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 76 has 257 bytes (fin? true) [2025-04-30T11:48:11.714037337Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/vpuginixoaobrxkadinbynibkvryjmlvkveynuwlllhmkffmvtergcxnpszdcemgagfmvufgkizwcnucitvqkugkvannttptkarjeygjaalvwtylelonctqckfugtsjnsvdiuruwzejzvcsqoyvksgavzlosfztqjtwvnuqzqymhsumfirkrnmwpixegbupqcfxkamgtdyhvdkndleawaklwpqxtvhlctbgzcrmdkcvxmnhkvxkbqxovep" on stream 76 [2025-04-30T11:48:11.714051334Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 76 [2025-04-30T11:48:11.714055642Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.714057926Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 80 has 257 bytes (fin? true) [2025-04-30T11:48:11.714060651Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/qavovwuyzkmrzcevajxnsktdqstdjwsglhqtxuzrdxemntntjodrzwxuulkzpppinknzlrxnxqyzfyferhrdnzesjkooznaffegewcogaktjrrbygzuppodnbmvsmqzmgsyxtmsgfawioxrggldahqkzesiojethbmirfcponbugqnuavrrezdrinmvrzhrrgtgwxgikayhqrmzvwuixhdtteryhfjjmtxsibcnhstsnqczrgenxorssal" on stream 80 [2025-04-30T11:48:11.714074156Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 80 [2025-04-30T11:48:11.714078434Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.714080738Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 84 has 257 bytes (fin? true) [2025-04-30T11:48:11.714083484Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/kmvhdgnwtoodzpvhcegjtdhfabyckaacxgjwbbuxbheshravbpdxtgchievxzzwmlssakwqlvbljjzxumgbgjedvrxgclritmzriwrnllozbmaicalmkylizazbuptvwnlxvtznxaskmmfgfvkdsvpfpunrpilawulrfarbchtyzrimrxwwybmranrobykvrwsprlnfydwjfbduarjipvwwflrajjehxyizieymutvrzwnqchsunjxezsy" on stream 84 [2025-04-30T11:48:11.714096548Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 84 [2025-04-30T11:48:11.714100956Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 245 bytes [2025-04-30T11:48:11.714103231Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 88 has 245 bytes (fin? false) [2025-04-30T11:48:11.714107148Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.714113570Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11197 out_len=1200 left=1169 [2025-04-30T11:48:11.714121325Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=43 pn=20 [2025-04-30T11:48:11.714123999Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=20 blocks=[0..7] ecn_counts=None [2025-04-30T11:48:11.714127175Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=72 off=0 len=32 fin=true [2025-04-30T11:48:11.714133668Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=994.197877ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2372 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 19, last_sent_packet: 20, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 504219800 }, next_time: Instant { tv_sec: 447, tv_nsec: 504219800 }, 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-30T11:48:11.714170005Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 504219800 } }) [2025-04-30T11:48:11.714174935Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.714180515Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.714188380Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11128 out_len=1200 left=1174 [2025-04-30T11:48:11.714194261Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=21 [2025-04-30T11:48:11.714198419Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=76 off=0 len=32 fin=true [2025-04-30T11:48:11.714207646Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=994.123589ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2436 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 20, last_sent_packet: 21, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 504294510 }, next_time: Instant { tv_sec: 447, tv_nsec: 504294510 }, 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-30T11:48:11.714247921Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 504294510 } }) [2025-04-30T11:48:11.714260214Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.714266596Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.714275192Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11064 out_len=1200 left=1174 [2025-04-30T11:48:11.714281184Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=22 [2025-04-30T11:48:11.714285361Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=80 off=0 len=32 fin=true [2025-04-30T11:48:11.714294859Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=994.036235ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2500 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 21, last_sent_packet: 22, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 504380421 }, next_time: Instant { tv_sec: 447, tv_nsec: 504380421 }, 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-30T11:48:11.714331768Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 504380421 } }) [2025-04-30T11:48:11.714336427Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.714342839Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.714350844Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11000 out_len=1200 left=1174 [2025-04-30T11:48:11.714356985Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=23 [2025-04-30T11:48:11.714361183Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=84 off=0 len=32 fin=true [2025-04-30T11:48:11.714370981Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=993.960323ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2564 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 22, last_sent_packet: 23, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 504456513 }, next_time: Instant { tv_sec: 447, tv_nsec: 504456513 }, 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-30T11:48:11.714409644Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 504456513 } }) [2025-04-30T11:48:11.714421666Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.714427828Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.714433899Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.714437646Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.715033227Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.715044558Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.715050830Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=1313 pn=8 [2025-04-30T11:48:11.715057021Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=92 off=0 len=257 fin=true [2025-04-30T11:48:11.715061289Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=96 off=0 len=257 fin=true [2025-04-30T11:48:11.715064185Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=100 off=0 len=257 fin=true [2025-04-30T11:48:11.715067020Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=104 off=0 len=257 fin=true [2025-04-30T11:48:11.715070176Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=108 off=0 len=245 fin=false [2025-04-30T11:48:11.715080525Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 1350 bytes [2025-04-30T11:48:11.715083381Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 88 is writable with capacity Ok(10936) [2025-04-30T11:48:11.715086066Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 92 is writable with capacity Ok(10936) [2025-04-30T11:48:11.715088330Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 96 is writable with capacity Ok(10936) [2025-04-30T11:48:11.715090544Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 100 is writable with capacity Ok(10936) [2025-04-30T11:48:11.715092738Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 104 is writable with capacity Ok(10936) [2025-04-30T11:48:11.715094912Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 108 is writable with capacity Ok(10936) [2025-04-30T11:48:11.715099160Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.715101525Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 92 has 257 bytes (fin? true) [2025-04-30T11:48:11.715105082Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/htrjwznftlfaxlynwdzgyvugiuzkxfxkyehzwvmnycjlbgyoxlznieuziogtpnrpfvrixdhpbpbswntwczehtrxmupgkehmmohryxzbdiqwgmcfywolbrypvifybojdbxrfxrayqyblrmzqnjprubcnsxdhiqhdbumvgqeprtogdjkdjinyuxishqqhlqhktzllzlvgiqqekvthgdqzvgmigxygevnedxsmdxefutrbrwcgomcjbawbrsz" on stream 92 [2025-04-30T11:48:11.715124688Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 92 [2025-04-30T11:48:11.715130349Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.715132693Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 96 has 257 bytes (fin? true) [2025-04-30T11:48:11.715135488Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/vzmsycuxerhvgmisqljfbaajhcanhzjmjcvmvwxundgpzvbmaxydspvmjltcxraaailobybdturpaebneavmjoizsgdlqxsiasufgoolrxusluwhapqxbgueozbzoclgvmtfbntzsszwdllziknjkbctuafvodsqkfpqchcldbotaiivgnopyhhxjbjatlfrsnwfiadvvgoctjjhtzcgkanzdomcqcqsqbujgikwtgcfvyyamutbtpwfsq" on stream 96 [2025-04-30T11:48:11.715148984Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 96 [2025-04-30T11:48:11.715158642Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.715161136Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 100 has 257 bytes (fin? true) [2025-04-30T11:48:11.715164182Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/ijgilzdwozmltkqrjetyxocsjgktbwwcfdllliwusarwmxpqmzfgjfnfqzekonzkpbxczdxriwohfmozdyousmwxyxalgculfwayfxsoyzprwpinczbintomomxjwmtipvnhwtchswophmqcpbsphsnqqqpptlpgjvjhpbfqiegmamtfadiqgwrlvpslusxghstwygtmsukqahgnoxxnsskkfsjypwhgcjlmrfmncodgpayciqocmlhhmy" on stream 100 [2025-04-30T11:48:11.715177858Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 100 [2025-04-30T11:48:11.715182316Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.715184590Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 104 has 257 bytes (fin? true) [2025-04-30T11:48:11.715187395Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/diqvjmsvqxqsraoydyvdyhvppkzramelztfoidzrdwwwrrlngxdnjsyuthhtbdxpasdsbqknweitjiwvrzupsjrvazrfydkugspvfgahzwrzwubctuqtukxfcymoqwwvtydbueaeibfhicgvxhwietrsrjacdblssztavdumufusufwaxcbgvltsqnbeepmqklnmtaspbmuadlcwwvtdkaixqlxekhxbbgwqvcaehdakuzvpilhnyyjwad" on stream 104 [2025-04-30T11:48:11.715204968Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 104 [2025-04-30T11:48:11.715209867Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 245 bytes [2025-04-30T11:48:11.715212142Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 108 has 245 bytes (fin? false) [2025-04-30T11:48:11.715216169Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.715223012Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10936 out_len=1200 left=1169 [2025-04-30T11:48:11.715227520Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=43 pn=24 [2025-04-30T11:48:11.715230135Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=21 blocks=[0..8] ecn_counts=None [2025-04-30T11:48:11.715233431Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=92 off=0 len=32 fin=true [2025-04-30T11:48:11.715240164Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=993.091391ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2633 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 23, last_sent_packet: 24, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 505328862 }, next_time: Instant { tv_sec: 447, tv_nsec: 505328862 }, 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-30T11:48:11.715277264Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 505328862 } }) [2025-04-30T11:48:11.715283275Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.715289386Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.715303603Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10867 out_len=1200 left=1174 [2025-04-30T11:48:11.715310035Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=25 [2025-04-30T11:48:11.715314283Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=96 off=0 len=32 fin=true [2025-04-30T11:48:11.715324261Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=993.006983ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2697 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 24, last_sent_packet: 25, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 505409613 }, next_time: Instant { tv_sec: 447, tv_nsec: 505409613 }, 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-30T11:48:11.715364557Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 505409613 } }) [2025-04-30T11:48:11.715369406Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.715375648Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.715384224Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10803 out_len=1200 left=1174 [2025-04-30T11:48:11.715390335Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=26 [2025-04-30T11:48:11.715394563Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=100 off=0 len=32 fin=true [2025-04-30T11:48:11.715404041Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=992.927234ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2761 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 25, last_sent_packet: 26, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 505489422 }, next_time: Instant { tv_sec: 447, tv_nsec: 505489422 }, 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-30T11:48:11.715456649Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 505489422 } }) [2025-04-30T11:48:11.715463181Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.715469323Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.715483419Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10739 out_len=1200 left=1174 [2025-04-30T11:48:11.715489861Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=27 [2025-04-30T11:48:11.715493979Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=104 off=0 len=32 fin=true [2025-04-30T11:48:11.715503998Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=992.827217ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2825 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 26, last_sent_packet: 27, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 505588628 }, next_time: Instant { tv_sec: 447, tv_nsec: 505588628 }, 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-30T11:48:11.715542970Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 505588628 } }) [2025-04-30T11:48:11.715547770Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.715551667Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.715556686Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.715560674Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.715966373Z TRACE quiche_server] got 1138 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.715976211Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.715982363Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=1101 pn=9 [2025-04-30T11:48:11.715988243Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=112 off=0 len=257 fin=true [2025-04-30T11:48:11.715994415Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=116 off=0 len=257 fin=true [2025-04-30T11:48:11.715997571Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=120 off=0 len=257 fin=true [2025-04-30T11:48:11.716000436Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=124 off=0 len=257 fin=true [2025-04-30T11:48:11.716003712Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=88 off=245 len=12 fin=true [2025-04-30T11:48:11.716006457Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=108 off=245 len=12 fin=true [2025-04-30T11:48:11.716016607Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 1138 bytes [2025-04-30T11:48:11.716019432Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 88 is writable with capacity Ok(10675) [2025-04-30T11:48:11.716022037Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 108 is writable with capacity Ok(10675) [2025-04-30T11:48:11.716024251Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 112 is writable with capacity Ok(10675) [2025-04-30T11:48:11.716031474Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 116 is writable with capacity Ok(10675) [2025-04-30T11:48:11.716033809Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 120 is writable with capacity Ok(10675) [2025-04-30T11:48:11.716036003Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 124 is writable with capacity Ok(10675) [2025-04-30T11:48:11.716040411Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.716042695Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 112 has 257 bytes (fin? true) [2025-04-30T11:48:11.716046322Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/ocmrsxzxjxwenfpvwmytyzrasylxzikatdpanoxtuwlvoqzfplgzwvdmznllvahfbnxuztbyuqbnbsdjtziwsolowybbqsanftwetpimvxyjcffxnfvzztfyhubrgonqfyuakjeewuxcixgujvrctwdxduuirtqyoynvwlkueiqttzcxwchsccbifblkgqcviotxboygxdmafvwaawvmldehmbzfldvcgmrvzgfyjtxsqpnvihzjkwhjfj" on stream 112 [2025-04-30T11:48:11.716065298Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 112 [2025-04-30T11:48:11.716070808Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.716073132Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 116 has 257 bytes (fin? true) [2025-04-30T11:48:11.716075927Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/dxrbkkoahsathrcxrruempyqowdemjnqwnbncfvlsfncxxinwlcvxwibwjmsigshswzlltwajetlcnmvzymrjwrzliqkqgddsfuzptavogowvwlrgqkjtuhwbiczvtuvqmmwfwotfbxqieetlcttaoqukulfrwrqjzisczqvkzjxoqhughyzkzefcbcddhstenusitztasufgtglrludzhohkgnsezwtubpwdhgbverhagcvwwdvtshgcj" on stream 116 [2025-04-30T11:48:11.716089162Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 116 [2025-04-30T11:48:11.716093721Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.716096035Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 120 has 257 bytes (fin? true) [2025-04-30T11:48:11.716098770Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/rnybpsgxcifjdtrbsmgxeijnnaxbyljtcnhmofxfvldhnuktbxsbhodrpiwlmrkefoqmjglsaobnmocicdeagsqhvcrcsxxxxlcalozgsnjohcpxrnngvoyegbuwhmfqqnshuyesngctjmlaxvegvocdonhyeuziajekppfeonjhbcndkpjejhkviawwgidygntqmodbhvzbupgiojbfglagtytvwcbifexrciekkiwslwcxkbnhlnlapa" on stream 120 [2025-04-30T11:48:11.716111945Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 120 [2025-04-30T11:48:11.716116243Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.716118527Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 124 has 257 bytes (fin? true) [2025-04-30T11:48:11.716121343Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/oyrhsaftrzwjsnejylbegjyudkyaxpzktwrvjayjjjqhncotviqwneaxdgmklanyyiybbqbsuwgamsobsrvthmsdavawogbmdeuifssfpzeosytmuglqgyjbykknbeuiidhcpowhithlhysihazwfdamemdomygwjjemzwllatfswrdvceulqxwlvbnvuppsvxhfpozzrfuaawajeziuffjyoonxbahxhenvlbxonwutbkfghjuedfqxtr" on stream 124 [2025-04-30T11:48:11.716134587Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 124 [2025-04-30T11:48:11.716138965Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 12 bytes [2025-04-30T11:48:11.716141250Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 88 has 12 bytes (fin? true) [2025-04-30T11:48:11.716144386Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/scytvoshissuhzguillhukaxdxuezkdiyrkbkenzngyhbhzoypsdeshropotjxbhwonetehcgptassqyuqyksbyajvhmtjxexzrbilswjocviumuomewsbzmkmfkvxxqrrbqsogywvpekllufhypeosqpfehilwerujaudwitkpmjqvlgkfkbpaghfdnbjymcxrqmtjmytxlkvxyaxvdqkrammpymuzujgptqchxgovbtxblmypsbzjmma" on stream 88 [2025-04-30T11:48:11.716161097Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 88 [2025-04-30T11:48:11.716165595Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 12 bytes [2025-04-30T11:48:11.716167850Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 108 has 12 bytes (fin? true) [2025-04-30T11:48:11.716170775Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/rvyfborgkrunsxbnwcyfbxtkvxkwfkrypookrpfoxiawilukkxaiyyoowtbqnnngjslpsaihsqxkinufsrmnpyjpnxkfeabnkqsbzimazntwwsgchddjlhepfonzsngnhmgemnojzjbgemwmtwtxejbwjtikejhdhzjbsahgkepacqgbgjmojxvmadtibiyaihsydxfusamsqsaxuubqpzocphjlukgalargbjgdtoxxzruiqdwevjrszk" on stream 108 [2025-04-30T11:48:11.716184801Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 108 [2025-04-30T11:48:11.716193708Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.716203526Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10675 out_len=1200 left=1169 [2025-04-30T11:48:11.716210138Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=43 pn=28 [2025-04-30T11:48:11.716214377Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=27 blocks=[0..9] ecn_counts=None [2025-04-30T11:48:11.716219616Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=112 off=0 len=32 fin=true [2025-04-30T11:48:11.716230316Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=992.100878ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2894 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 27, last_sent_packet: 28, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 506308294 }, next_time: Instant { tv_sec: 447, tv_nsec: 506308294 }, 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-30T11:48:11.716272886Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 506308294 } }) [2025-04-30T11:48:11.716278416Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.716287603Z TRACE quiche_server] got 316 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.716292964Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.716301990Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=279 pn=10 [2025-04-30T11:48:11.716310577Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=128 off=0 len=257 fin=true [2025-04-30T11:48:11.716325044Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 316 bytes [2025-04-30T11:48:11.716329432Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 128 is writable with capacity Ok(10606) [2025-04-30T11:48:11.716341023Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.716343438Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 128 has 257 bytes (fin? true) [2025-04-30T11:48:11.716346994Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/ivqxjbivkgcwarovmmdurcamajcmlbromzubdzrsmdartidkfmzqpbowngukgtcojuisfpkhiiwqjwulcboqxajaigxhymxalbrwughouzibhtwuzmdpuqmlxgcgstdfwavbisyzcdgojxmbuwahipvoiojtwedapbthmizozxeneprazypgvrhbegapsxgpgloftlhdpmdtjyrbuzlkfbvizddrasoznzbdyjypuodhjirtznakcnmfmp" on stream 128 [2025-04-30T11:48:11.716365770Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 128 [2025-04-30T11:48:11.716372101Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.716378634Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10606 out_len=1200 left=1169 [2025-04-30T11:48:11.716382761Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=43 pn=29 [2025-04-30T11:48:11.716385256Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=9 blocks=[0..10] ecn_counts=None [2025-04-30T11:48:11.716388462Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=116 off=0 len=32 fin=true [2025-04-30T11:48:11.716395175Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=991.936441ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2963 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 28, last_sent_packet: 29, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 506484864 }, next_time: Instant { tv_sec: 447, tv_nsec: 506484864 }, 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-30T11:48:11.716437143Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 506484864 } }) [2025-04-30T11:48:11.716445389Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.716451069Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.716459124Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10537 out_len=1200 left=1174 [2025-04-30T11:48:11.716465226Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=30 [2025-04-30T11:48:11.716469253Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=120 off=0 len=32 fin=true [2025-04-30T11:48:11.716479582Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=991.851562ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3027 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 29, last_sent_packet: 30, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 506564834 }, next_time: Instant { tv_sec: 447, tv_nsec: 506564834 }, 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-30T11:48:11.716525639Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 506564834 } }) [2025-04-30T11:48:11.716531670Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.716540617Z TRACE quiche_server] got 316 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.716545887Z TRACE quiche_server] got packet ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f [2025-04-30T11:48:11.716554743Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt ZeroRTT version=1 dcid=24628a3183e4929da25e2e6e7126ba9da112c57e scid=6e61099753ec072f len=279 pn=11 [2025-04-30T11:48:11.716563409Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=132 off=0 len=257 fin=true [2025-04-30T11:48:11.716577596Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 316 bytes [2025-04-30T11:48:11.716581904Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 132 is writable with capacity Ok(10473) [2025-04-30T11:48:11.716588366Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.716590730Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 132 has 257 bytes (fin? true) [2025-04-30T11:48:11.716594377Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/scnwsckvzeobzcoydndahbcoawackinmjwwnfrkmjbuarnhaiwormdcxaojhpejyrphfmdavilhnmzknweiljkjebwqgzzzwpfltmevgoyiuvhbsahjlnhfdesjvobqzjpgxzkllhzzquhjcpqrashcjkeofdyzgwgxglvbtlfnpkgaljkpamsxemrznesufalwegqvcmyoyraeayntephsuqbylgmswllullrsnccolddylohuntwjela" on stream 132 [2025-04-30T11:48:11.716613653Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 132 [2025-04-30T11:48:11.716619895Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.716626267Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10473 out_len=1200 left=1169 [2025-04-30T11:48:11.716630295Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=43 pn=31 [2025-04-30T11:48:11.716632829Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=9 blocks=[0..11] ecn_counts=None [2025-04-30T11:48:11.716635955Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=124 off=0 len=32 fin=true [2025-04-30T11:48:11.716642307Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=991.689308ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3096 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 30, last_sent_packet: 31, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 506732588 }, next_time: Instant { tv_sec: 447, tv_nsec: 506732588 }, 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-30T11:48:11.716690497Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 506732588 } }) [2025-04-30T11:48:11.716699053Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.716705155Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.716713350Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10404 out_len=1200 left=1174 [2025-04-30T11:48:11.716719752Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=32 [2025-04-30T11:48:11.716723930Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=88 off=0 len=32 fin=true [2025-04-30T11:48:11.716734249Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=991.596825ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3160 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 31, last_sent_packet: 32, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 506818999 }, next_time: Instant { tv_sec: 447, tv_nsec: 506818999 }, 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-30T11:48:11.716771920Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 506818999 } }) [2025-04-30T11:48:11.716776498Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.716782600Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.716790244Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10340 out_len=1200 left=1174 [2025-04-30T11:48:11.716796235Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=33 [2025-04-30T11:48:11.716800593Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=108 off=0 len=32 fin=true [2025-04-30T11:48:11.716810702Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=991.520452ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3224 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 32, last_sent_packet: 33, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 506896143 }, next_time: Instant { tv_sec: 447, tv_nsec: 506896143 }, 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-30T11:48:11.716855135Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 506896143 } }) [2025-04-30T11:48:11.716860886Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.716866977Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.716875563Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10276 out_len=1200 left=1174 [2025-04-30T11:48:11.716881845Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=34 [2025-04-30T11:48:11.716885993Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=128 off=0 len=32 fin=true [2025-04-30T11:48:11.716912973Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=991.41787ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3288 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 33, last_sent_packet: 34, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 506980852 }, next_time: Instant { tv_sec: 447, tv_nsec: 506980852 }, 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-30T11:48:11.716957627Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 506980852 } }) [2025-04-30T11:48:11.716962817Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.716969499Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.716978025Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=10212 out_len=1200 left=1174 [2025-04-30T11:48:11.716984347Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=35 [2025-04-30T11:48:11.716988494Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=132 off=0 len=32 fin=true [2025-04-30T11:48:11.716998694Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=991.33242ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3352 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 34, last_sent_packet: 35, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 447, tv_nsec: 507083284 }, next_time: Instant { tv_sec: 447, tv_nsec: 507083284 }, 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-30T11:48:11.717043568Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 507083284 } }) [2025-04-30T11:48:11.717049519Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.717055490Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.717061351Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.717065058Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.742322417Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.742336533Z TRACE quiche_server] got packet Initial version=1 dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 scid=6e61099753ec072f token= [2025-04-30T11:48:11.742346061Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Initial version=1 dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 scid=6e61099753ec072f token= len=22 pn=1 [2025-04-30T11:48:11.742354457Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=48 blocks=[0..0] ecn_counts=None [2025-04-30T11:48:11.742360067Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 0 [2025-04-30T11:48:11.742378231Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Handshake version=1 dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 scid=6e61099753ec072f len=77 pn=0 [2025-04-30T11:48:11.742383802Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=48 blocks=[1..1] ecn_counts=None [2025-04-30T11:48:11.742387138Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 1 [2025-04-30T11:48:11.742390905Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm CRYPTO off=0 len=52 [2025-04-30T11:48:11.742398940Z TRACE quiche::tls] e7df5084105002b7a70e6dfe9756ab82b31d58a6 set read secret lvl=OneRTT [2025-04-30T11:48:11.742418366Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 dropped epoch 1 state [2025-04-30T11:48:11.742422835Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 connection established: proto=Ok("hq-interop") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=None resumed=true TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1472, initial_max_data: 4611686018427387903, initial_max_stream_data_bidi_local: 1250000, initial_max_stream_data_bidi_remote: 1250000, initial_max_stream_data_uni: 1250000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 5, initial_source_connection_id: Some(6e61099753ec072f), retry_source_connection_id: None, max_datagram_frame_size: Some(65535), unknown_params: None } [2025-04-30T11:48:11.742459203Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 dropped epoch 0 state [2025-04-30T11:48:11.742463170Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=1155 pn=12 [2025-04-30T11:48:11.742469302Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=10 blocks=[2..6] ecn_counts=None [2025-04-30T11:48:11.742472888Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 2 [2025-04-30T11:48:11.742476064Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 3 [2025-04-30T11:48:11.742483208Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 4 [2025-04-30T11:48:11.742486304Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 5 [2025-04-30T11:48:11.742489429Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 6 [2025-04-30T11:48:11.742493477Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=136 off=0 len=257 fin=true [2025-04-30T11:48:11.742497665Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=140 off=0 len=257 fin=true [2025-04-30T11:48:11.742501372Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=144 off=0 len=257 fin=true [2025-04-30T11:48:11.742505119Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=148 off=0 len=257 fin=true [2025-04-30T11:48:11.742508936Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=152 off=0 len=82 fin=false [2025-04-30T11:48:11.742527631Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 1350 bytes [2025-04-30T11:48:11.742531809Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 136 is writable with capacity Ok(11605) [2025-04-30T11:48:11.742535355Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 140 is writable with capacity Ok(11605) [2025-04-30T11:48:11.742538141Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 144 is writable with capacity Ok(11605) [2025-04-30T11:48:11.742540956Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 148 is writable with capacity Ok(11605) [2025-04-30T11:48:11.742543721Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 152 is writable with capacity Ok(11605) [2025-04-30T11:48:11.742548620Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.742551575Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 136 has 257 bytes (fin? true) [2025-04-30T11:48:11.742556425Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/gjdrqwnrqqpnrdvzcpmrlzaimnshggbwswtwjedvzhjnlvwxkzrxojacrvmyojnksmdejzldkcfrnxgugyadnuzihbnrkprfcpldfgvjnnhvqhqkvoulclaimtfenugjmdvaqqlsulaqrwtrjugepvcwejyvzmjolglkzfhoxhvapxhpxgzilnzjqmjqhiwyvfbekdcbgtibljjqgkxceilnfynknkyfpyyrweysbsejtziakwhhibeixf" on stream 136 [2025-04-30T11:48:11.742582213Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 136 [2025-04-30T11:48:11.742589126Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.742592061Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 140 has 257 bytes (fin? true) [2025-04-30T11:48:11.742595748Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/qootlfzbzveyptljpqopzgfilvqgoklajqzhteocniajlpxjuwpcifpkusuwmzsiesgjiswtgshprvuzfiyytcixctomzwmlfruvjynsbangavyutibowlowoozwejxidoqzybjpraonyjyucefppshuqvsfswsossosbdnpyftmbhpwjeqkivficoynryjsykvyjoxtlgbyskarbhdopjusomcukuhvupqkeddgyloatojesqtuxvapib" on stream 140 [2025-04-30T11:48:11.742613321Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 140 [2025-04-30T11:48:11.742618781Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.742621627Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 144 has 257 bytes (fin? true) [2025-04-30T11:48:11.742625143Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/dkqeafrhpyqfaurgtlrdvwubhrrhbterpdjfiukxsprgdyycynatpjegvwzioxxycfqbeigkjfjwxaoljtazgfxrcwhbgwbayyslpbjoysmhzlljqntdzamhbhgjkxvoysnjhoiskrhdbtqhrgkwwltmqavodthyhnhuhgacojecdvmtnfudguemzlmdpjzxnzhsrnjoizahlhujhgndksygnlfdgsjhwipxdrakjthpbqacowxvmdqhcz" on stream 144 [2025-04-30T11:48:11.742646243Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 144 [2025-04-30T11:48:11.742651813Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.742656592Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 148 has 257 bytes (fin? true) [2025-04-30T11:48:11.742663605Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/rqxinccpqhwphtizoyanympwkupjeszziaobfshxyvwxbceeiyldmkiomsxhhgfzrnijbenogqzgrjuxfiqxenqncybcdyaxqxsspnqootxfcsvgctbbmqazwquzsofdrxrulacelaxejxvkjgkxyeqnthqtqswlltngijntgfobcpzcegasptyifeivpsqwcfoxuodvxgmdxhapxgxmlzrhepstwtsrdulrfuilcqctnmvirhtlxqqujx" on stream 148 [2025-04-30T11:48:11.742692720Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 148 [2025-04-30T11:48:11.742704091Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 82 bytes [2025-04-30T11:48:11.742708970Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 152 has 82 bytes (fin? false) [2025-04-30T11:48:11.742716514Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.742727404Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=11605 out_len=1350 left=1319 [2025-04-30T11:48:11.742737002Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=44 pn=36 [2025-04-30T11:48:11.742741851Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=32 blocks=[2..12] ecn_counts=None [2025-04-30T11:48:11.742747903Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm HANDSHAKE_DONE [2025-04-30T11:48:11.742751169Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=136 off=0 len=32 fin=true [2025-04-30T11:48:11.742761038Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=95.789605ms latest_rtt=32.272278ms srtt=32.923064ms min_rtt=32.272278ms rttvar=9.476847ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1965 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1457, delivered_time: Instant { tv_sec: 447, tv_nsec: 532572967 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 35, last_sent_packet: 36, largest_acked: 6, rate_sample: RateSample { delivery_rate: 44135, is_app_limited: true, interval: 33.011811ms, delivered: 1457, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 499442374 }), send_elapsed: 0ns, ack_elapsed: 33.011811ms, rtt: 33.011811ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 512558, last_update: Instant { tv_sec: 447, tv_nsec: 507083284 }, next_time: Instant { tv_sec: 447, tv_nsec: 507083284 }, 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-30T11:48:11.742808015Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 70 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 507083284 } }) [2025-04-30T11:48:11.742814427Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.742821961Z TRACE quiche_server] got 482 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.742827802Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.742837481Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=461 pn=13 [2025-04-30T11:48:11.742856366Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=156 off=0 len=257 fin=true [2025-04-30T11:48:11.742863960Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm STREAM id=152 off=82 len=175 fin=true [2025-04-30T11:48:11.742876554Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 482 bytes [2025-04-30T11:48:11.742882284Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 152 is writable with capacity Ok(11535) [2025-04-30T11:48:11.742887574Z DEBUG quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 response stream 156 is writable with capacity Ok(11535) [2025-04-30T11:48:11.742924303Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 257 bytes [2025-04-30T11:48:11.742929502Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 156 has 257 bytes (fin? true) [2025-04-30T11:48:11.742936656Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/ooulmnjveaqqwspnjkyfufmijwxcvrwmhtmuawxjfkuarurejesnglycqlbmygyuuoagokmwlsxhcpglzrnaurpjkistuamnzmkhiboanqbvfzadcjaqmxacvikqqwmugfsgxhkjtpqcmjjqbihnccwyknwezuemijzavuqibmpqiljfrkabnryqyobfebyutesgqsrsylyybnhughvvfxnvmqfjrbzmljtektsrnlgxezjnosoxkdnizz" on stream 156 [2025-04-30T11:48:11.742968776Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 156 [2025-04-30T11:48:11.742981009Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 received 175 bytes [2025-04-30T11:48:11.742985758Z TRACE quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 stream 152 has 175 bytes (fin? true) [2025-04-30T11:48:11.742992611Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 got GET request for "/www/yrzihetszkhivagochoxyjtrcxrqtylgackrsfsuhiurgwxjhbfnpqwcrtuthrzbegvxqraosjrpnezydxkmyanpqezbqcigfthbjpqmxzfczegkzpkmptmopjejskaaxmhnczvpvloulzdrkfmqphnuxdjjidbqguzdvjomtjcysgkxqfpwmdblltfiimuxxskqdonaxwsoujazrorrwxkkdywqaegfxkmeohduqqbbzxscjzevnrwfej" on stream 152 [2025-04-30T11:48:11.743020593Z INFO quiche_apps::common] e7df5084105002b7a70e6dfe9756ab82b31d58a6 sending response of size 32 on stream 152 [2025-04-30T11:48:11.743030551Z TRACE quiche_server] got 234 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.743034839Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.743041021Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=213 pn=14 [2025-04-30T11:48:11.743049266Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[4b, 05, fb, b7, 6a, 87, a7, 96] reset_token=[0e, cc, 99, b2, ff, 27, 56, c7, a8, 48, 05, a6, 90, d0, ac, 86] [2025-04-30T11:48:11.743057261Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[07, 39, ec, 7b, 8f, ef, 17, 37] reset_token=[86, 5e, 7e, 08, 95, 77, 12, fe, 1b, 01, 4f, 5e, 28, 2f, 51, 17] [2025-04-30T11:48:11.743063103Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[4a, 91, cc, 5e, 5d, 1f, aa, c4] reset_token=[6c, 83, 7e, 5c, 2f, 00, ec, 8e, 60, 40, f1, 45, 77, 04, 8b, c3] [2025-04-30T11:48:11.743068763Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[08, ac, ef, 9e, 2e, 39, 7a, 22] reset_token=[59, 74, 2d, f3, 75, 54, 2d, 80, 3e, 98, e6, 8f, 94, c4, e7, 33] [2025-04-30T11:48:11.743078511Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[86, a5, 1b, a8, 6d, c4, 91, 4b] reset_token=[b0, 31, c4, 6d, cc, 3e, f6, 4b, 27, a1, e9, 96, 81, e1, ca, e5] [2025-04-30T11:48:11.743103608Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[36, a1, d7, 81, 28, 79, 03, 39] reset_token=[2f, 8a, 46, 3d, bf, 3d, 9d, dd, 65, ea, 3d, 77, 55, 49, 67, 41] [2025-04-30T11:48:11.743114399Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[2f, 6a, 1b, 22, 34, 43, a2, a2] reset_token=[81, 5c, cc, 29, 8e, 68, e2, a6, 1b, 0f, ce, 31, 3e, 50, c8, 1b] [2025-04-30T11:48:11.743134746Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 234 bytes [2025-04-30T11:48:11.743145437Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.743151688Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.743159463Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=15 [2025-04-30T11:48:11.743165434Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=3 blocks=[2..9] ecn_counts=None [2025-04-30T11:48:11.743169482Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 7 [2025-04-30T11:48:11.743172717Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 8 [2025-04-30T11:48:11.743175503Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 9 [2025-04-30T11:48:11.743189489Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.743195420Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.743198877Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.743203365Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=16 [2025-04-30T11:48:11.743208034Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=4 blocks=[2..12] ecn_counts=None [2025-04-30T11:48:11.743211350Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 10 [2025-04-30T11:48:11.743214195Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 11 [2025-04-30T11:48:11.743217010Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 12 [2025-04-30T11:48:11.743227370Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.743232640Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.743235996Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.743240304Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=17 [2025-04-30T11:48:11.743244812Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=4 blocks=[2..14] ecn_counts=None [2025-04-30T11:48:11.743248049Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 13 [2025-04-30T11:48:11.743250914Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 14 [2025-04-30T11:48:11.743259290Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.743263547Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.743269719Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12059 out_len=1350 left=1319 [2025-04-30T11:48:11.743274889Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=43 pn=37 [2025-04-30T11:48:11.743283455Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm ACK delay=3 blocks=[6..17] ecn_counts=None [2025-04-30T11:48:11.743286881Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=140 off=0 len=32 fin=true [2025-04-30T11:48:11.743310034Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=77.165926ms latest_rtt=30.60019ms srtt=32.325376ms min_rtt=30.60019ms rttvar=4.971371ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1510 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1981, delivered_time: Instant { tv_sec: 447, tv_nsec: 533350331 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 36, last_sent_packet: 37, largest_acked: 14, rate_sample: RateSample { delivery_rate: 60008, is_app_limited: true, interval: 33.011811ms, delivered: 1981, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 499442374 }), send_elapsed: 0ns, ack_elapsed: 33.011811ms, rtt: 33.011811ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522035, last_update: Instant { tv_sec: 447, tv_nsec: 533376651 }, next_time: Instant { tv_sec: 447, tv_nsec: 533376651 }, 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-30T11:48:11.743372010Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 533376651 } }) [2025-04-30T11:48:11.743382790Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.743393691Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.743400383Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.743409611Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=18 [2025-04-30T11:48:11.743419409Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=2 blocks=[2..16] ecn_counts=None [2025-04-30T11:48:11.743425521Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 15 [2025-04-30T11:48:11.743430911Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 16 [2025-04-30T11:48:11.743447051Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.743452461Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.743458342Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12121 out_len=1350 left=1324 [2025-04-30T11:48:11.743463181Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=38 [2025-04-30T11:48:11.743466246Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=144 off=0 len=32 fin=true [2025-04-30T11:48:11.743473440Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=73.83242ms latest_rtt=30.482691ms srtt=32.09504ms min_rtt=30.482691ms rttvar=4.189199ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1443 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2112, delivered_time: Instant { tv_sec: 447, tv_nsec: 533518827 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 37, last_sent_packet: 38, largest_acked: 16, rate_sample: RateSample { delivery_rate: 63977, is_app_limited: true, interval: 33.011811ms, delivered: 2112, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 499442374 }), send_elapsed: 0ns, ack_elapsed: 33.011811ms, rtt: 33.011811ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 525782, last_update: Instant { tv_sec: 447, tv_nsec: 533376651 }, next_time: Instant { tv_sec: 447, tv_nsec: 533376651 }, 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-30T11:48:11.743533152Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 533376651 } }) [2025-04-30T11:48:11.743542740Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.743551035Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.743556054Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.743563869Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=19 [2025-04-30T11:48:11.743571864Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=1 blocks=[2..18] ecn_counts=None [2025-04-30T11:48:11.743577154Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 17 [2025-04-30T11:48:11.743580831Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 18 [2025-04-30T11:48:11.743594016Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.743598514Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.743603133Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12184 out_len=1350 left=1324 [2025-04-30T11:48:11.743607180Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=39 [2025-04-30T11:48:11.743609615Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=148 off=0 len=32 fin=true [2025-04-30T11:48:11.743615385Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=71.071022ms latest_rtt=30.468253ms srtt=31.891691ms min_rtt=30.468253ms rttvar=3.548595ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1380 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2239, delivered_time: Instant { tv_sec: 447, tv_nsec: 533673135 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 38, last_sent_packet: 39, largest_acked: 18, rate_sample: RateSample { delivery_rate: 67824, is_app_limited: true, interval: 33.011811ms, delivered: 2239, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 499442374 }), send_elapsed: 0ns, ack_elapsed: 33.011811ms, rtt: 33.011811ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 529134, last_update: Instant { tv_sec: 447, tv_nsec: 533376651 }, next_time: Instant { tv_sec: 447, tv_nsec: 533376651 }, 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-30T11:48:11.743649920Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 533376651 } }) [2025-04-30T11:48:11.743654809Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.743668144Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.743674737Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12120 out_len=1350 left=1324 [2025-04-30T11:48:11.743680978Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=40 [2025-04-30T11:48:11.743685206Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=156 off=0 len=32 fin=true [2025-04-30T11:48:11.743694814Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=71.062026ms latest_rtt=30.468253ms srtt=31.891691ms min_rtt=30.468253ms rttvar=3.548595ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1444 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2239, delivered_time: Instant { tv_sec: 447, tv_nsec: 533673135 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 39, last_sent_packet: 40, largest_acked: 18, rate_sample: RateSample { delivery_rate: 67824, is_app_limited: true, interval: 33.011811ms, delivered: 2239, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 499442374 }), send_elapsed: 0ns, ack_elapsed: 33.011811ms, rtt: 33.011811ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 529134, last_update: Instant { tv_sec: 447, tv_nsec: 533376651 }, next_time: Instant { tv_sec: 447, tv_nsec: 533376651 }, 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-30T11:48:11.743738055Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 533376651 } }) [2025-04-30T11:48:11.743744237Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.743750589Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.743757562Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12056 out_len=1350 left=1324 [2025-04-30T11:48:11.743763483Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=38 pn=41 [2025-04-30T11:48:11.743767630Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm STREAM id=152 off=0 len=32 fin=true [2025-04-30T11:48:11.743777319Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=71.061866ms latest_rtt=30.468253ms srtt=31.891691ms min_rtt=30.468253ms rttvar=3.548595ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1508 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2239, delivered_time: Instant { tv_sec: 447, tv_nsec: 533673135 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 40, last_sent_packet: 41, largest_acked: 18, rate_sample: RateSample { delivery_rate: 67824, is_app_limited: true, interval: 33.011811ms, delivered: 2239, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 499442374 }), send_elapsed: 0ns, ack_elapsed: 33.011811ms, rtt: 33.011811ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 529134, last_update: Instant { tv_sec: 447, tv_nsec: 533376651 }, next_time: Instant { tv_sec: 447, tv_nsec: 533376651 }, 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-30T11:48:11.743822543Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 64 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 533376651 } }) [2025-04-30T11:48:11.743827602Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.743834065Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.743838282Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.743841919Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.744593085Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.744603043Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.744608664Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=20 [2025-04-30T11:48:11.744614054Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=3 blocks=[2..20] ecn_counts=None [2025-04-30T11:48:11.744617560Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 19 [2025-04-30T11:48:11.744619985Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 20 [2025-04-30T11:48:11.744632138Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.744636285Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.744639080Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.744641285Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.744732425Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.744738477Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.744743326Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=21 [2025-04-30T11:48:11.744748164Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=1 blocks=[2..22] ecn_counts=None [2025-04-30T11:48:11.744751491Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 21 [2025-04-30T11:48:11.744753925Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 22 [2025-04-30T11:48:11.744764125Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.744768112Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.744770807Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.744773001Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.745690808Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.745699484Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.745704343Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=22 [2025-04-30T11:48:11.745709192Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=2 blocks=[2..24] ecn_counts=None [2025-04-30T11:48:11.745712448Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 23 [2025-04-30T11:48:11.745714863Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 24 [2025-04-30T11:48:11.745725473Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.745729450Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.745733879Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12386 out_len=1350 left=1324 [2025-04-30T11:48:11.745742815Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx pkt Short dcid=6e61099753ec072f key_phase=false len=4 pn=42 [2025-04-30T11:48:11.745745500Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm MAX_STREAMS type=bidi max=123 [2025-04-30T11:48:11.745748115Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 tx frm PADDING len=1 [2025-04-30T11:48:11.745754136Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 timer=65.207136ms latest_rtt=30.485178ms srtt=31.423007ms min_rtt=30.468253ms rttvar=2.201773ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1144 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2633, delivered_time: Instant { tv_sec: 447, tv_nsec: 535814040 }, first_sent_time: Instant { tv_sec: 447, tv_nsec: 499442374 }, end_of_app_limited: 41, last_sent_packet: 42, largest_acked: 24, rate_sample: RateSample { delivery_rate: 79759, is_app_limited: true, interval: 33.011811ms, delivered: 2633, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 447, tv_nsec: 499442374 }), send_elapsed: 0ns, ack_elapsed: 33.011811ms, rtt: 33.011811ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 537026, last_update: Instant { tv_sec: 447, tv_nsec: 533376651 }, next_time: Instant { tv_sec: 447, tv_nsec: 533376651 }, 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-30T11:48:11.745802096Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 written 30 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:58245, at: Instant { tv_sec: 447, tv_nsec: 533376651 } }) [2025-04-30T11:48:11.745810802Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.745819649Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.745824698Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.745832443Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=23 [2025-04-30T11:48:11.745840278Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=1 blocks=[2..26] ecn_counts=None [2025-04-30T11:48:11.745845738Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 25 [2025-04-30T11:48:11.745849004Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 26 [2025-04-30T11:48:11.745860325Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.745864633Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.745867428Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.745869642Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.746685318Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.746694415Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.746699445Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=24 [2025-04-30T11:48:11.746704244Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=2 blocks=[2..28] ecn_counts=None [2025-04-30T11:48:11.746707510Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 27 [2025-04-30T11:48:11.746709844Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 28 [2025-04-30T11:48:11.746726385Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.746730553Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.746733298Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.746735502Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.746956486Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.746965262Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.746970201Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=25 [2025-04-30T11:48:11.746974950Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=2 blocks=[2..30] ecn_counts=None [2025-04-30T11:48:11.746978296Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 29 [2025-04-30T11:48:11.746980801Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 30 [2025-04-30T11:48:11.746992633Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.746996661Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.746999366Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.747001560Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.747123368Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.747129519Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.747134338Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=26 [2025-04-30T11:48:11.747138977Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=3 blocks=[2..32] ecn_counts=None [2025-04-30T11:48:11.747142333Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 31 [2025-04-30T11:48:11.747145038Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 32 [2025-04-30T11:48:11.747155247Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.747159205Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.747161920Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.747164104Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.747379907Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.747385979Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.747390748Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=27 [2025-04-30T11:48:11.747395457Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=2 blocks=[2..34] ecn_counts=None [2025-04-30T11:48:11.747398803Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 33 [2025-04-30T11:48:11.747401147Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 34 [2025-04-30T11:48:11.747423960Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.747428258Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.747430993Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.747433177Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.773316321Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.773338733Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.773348050Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=28 [2025-04-30T11:48:11.773356756Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=4 blocks=[7..36] ecn_counts=None [2025-04-30T11:48:11.773362177Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 35 [2025-04-30T11:48:11.773365593Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 36 [2025-04-30T11:48:11.773383987Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.773389418Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.773393235Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.773396130Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.773988759Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.773999489Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.774005861Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=29 [2025-04-30T11:48:11.774011962Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=2 blocks=[15..38] ecn_counts=None [2025-04-30T11:48:11.774016150Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 37 [2025-04-30T11:48:11.774019526Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 38 [2025-04-30T11:48:11.774032961Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.774037931Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.774041307Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.774044072Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.774149570Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.774157304Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.774163385Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=22 pn=30 [2025-04-30T11:48:11.774169427Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=3 blocks=[15..40] ecn_counts=None [2025-04-30T11:48:11.774173575Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 39 [2025-04-30T11:48:11.774176710Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 40 [2025-04-30T11:48:11.774189855Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 43 bytes [2025-04-30T11:48:11.774194774Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.774198161Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.774200956Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.774383046Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.774390120Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.774396171Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx pkt Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false len=25 pn=31 [2025-04-30T11:48:11.774402262Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm ACK delay=14 blocks=[15..41] ecn_counts=None [2025-04-30T11:48:11.774406410Z TRACE quiche::recovery::congestion::recovery] e7df5084105002b7a70e6dfe9756ab82b31d58a6 packet newly acked 41 [2025-04-30T11:48:11.774417100Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 rx frm APPLICATION_CLOSE err=0 reason=[] [2025-04-30T11:48:11.774431577Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 46 bytes [2025-04-30T11:48:11.774436506Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.774439392Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.774442177Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.776180388Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:58245 to [::]:443 [2025-04-30T11:48:11.776190737Z TRACE quiche_server] got packet Short dcid=e7df5084105002b7a70e6dfe9756ab82b31d58a6 key_phase=false [2025-04-30T11:48:11.776195466Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 processed 47 bytes [2025-04-30T11:48:11.776199163Z TRACE quiche_server] recv() would block [2025-04-30T11:48:11.776201408Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.776203541Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.929411663Z TRACE quiche_server] timed out [2025-04-30T11:48:11.929442451Z TRACE quiche] e7df5084105002b7a70e6dfe9756ab82b31d58a6 draining timeout expired [2025-04-30T11:48:11.929489899Z TRACE quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 done writing [2025-04-30T11:48:11.929493947Z TRACE quiche_server] Collecting garbage [2025-04-30T11:48:11.929497985Z INFO quiche_server] e7df5084105002b7a70e6dfe9756ab82b31d58a6 connection collected recv=35 sent=43 lost=0 retrans=0 sent_bytes=3777 recv_bytes=13554 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:58245 validation_state=Validated active=true recv=35 sent=43 lost=0 retrans=0 rtt=33.060767ms min_rtt=Some(30.424996ms) rttvar=4.602034ms cwnd=13500 sent_bytes=3777 recv_bytes=13554 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=44438]